[01:17:38.473] New invocation is queued and will start shortly
[01:17:39.554] Starting the invocation (attempt 1)
[01:17:39.591] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:17:39.591] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:17:39.591] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027043525921500832",
"parameters_json": "{\"builder_name\":\"win32-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"42c20055796bbb6fa632851e20887f23d0e482bf\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win32-official",
"scheduler_invocation_id:9027043525921500832",
"scheduler_job_id:chromium/win32-official",
"user_agent:luci-scheduler",
"buildset:commit/git/42c20055796bbb6fa632851e20887f23d0e482bf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/42c20055796bbb6fa632851e20887f23d0e482bf",
"gitiles_ref:refs/heads/master"
]
}
[01:17:40.298] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607303859672958",
"id": "8861613463998502192",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"42c20055796bbb6fa632851e20887f23d0e482bf\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607303860144609",
"tags": [
"build_address:luci.chromium.ci/win32-official/1068",
"builder:win32-official",
"buildset:commit/git/42c20055796bbb6fa632851e20887f23d0e482bf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/42c20055796bbb6fa632851e20887f23d0e482bf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027043525921500832",
"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/8861613463998502192/+/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": "1607303860144724",
"url": "https://ci.chromium.org/b/8861613463998502192",
"utcnow_ts": "1607303860286730"
}
}
[01:17:40.298] Task URL: https://ci.chromium.org/b/8861613463998502192
[01:17:40.298] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:2:0) after 1m0s
[01:18:20.038] Received PubSub notification, asking Buildbucket for the build status
[01:18:20.132] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:40.461] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:2:0)
[01:18:40.461] Timer tick, asking Buildbucket for the build status
[01:18:40.558] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:40.558] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:4:0) after 1m0s
[01:19:40.689] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:4:0)
[01:19:40.689] Timer tick, asking Buildbucket for the build status
[01:19:40.797] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:40.797] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:5:0) after 1m0s
[01:20:40.841] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:5:0)
[01:20:40.841] Timer tick, asking Buildbucket for the build status
[01:20:41.394] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:41.394] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:6:0) after 1m0s
[01:21:41.450] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:6:0)
[01:21:41.450] Timer tick, asking Buildbucket for the build status
[01:21:41.537] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:41.537] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:7:0) after 1m0s
[01:22:41.579] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:7:0)
[01:22:41.579] Timer tick, asking Buildbucket for the build status
[01:22:42.237] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:42.237] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:8:0) after 1m0s
[01:23:42.348] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:8:0)
[01:23:42.348] Timer tick, asking Buildbucket for the build status
[01:23:42.445] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:42.445] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:9:0) after 1m0s
[01:24:42.548] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:9:0)
[01:24:42.548] Timer tick, asking Buildbucket for the build status
[01:24:42.785] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:42.785] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:10:0) after 1m0s
[01:25:42.832] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:10:0)
[01:25:42.832] Timer tick, asking Buildbucket for the build status
[01:25:43.044] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:43.044] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:11:0) after 1m0s
[01:26:43.135] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:11:0)
[01:26:43.135] Timer tick, asking Buildbucket for the build status
[01:26:43.258] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:43.258] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:12:0) after 1m0s
[01:27:43.330] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:12:0)
[01:27:43.330] Timer tick, asking Buildbucket for the build status
[01:27:43.441] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:43.442] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:13:0) after 1m0s
[01:28:43.460] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:13:0)
[01:28:43.460] Timer tick, asking Buildbucket for the build status
[01:28:43.557] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:43.557] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:14:0) after 1m0s
[01:29:43.603] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:14:0)
[01:29:43.603] Timer tick, asking Buildbucket for the build status
[01:29:43.781] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:43.781] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:15:0) after 1m0s
[01:30:43.829] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:15:0)
[01:30:43.829] Timer tick, asking Buildbucket for the build status
[01:30:43.920] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:43.920] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:16:0) after 1m0s
[01:31:43.961] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:16:0)
[01:31:43.962] Timer tick, asking Buildbucket for the build status
[01:31:44.056] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:44.056] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:17:0) after 1m0s
[01:32:44.214] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:17:0)
[01:32:44.214] Timer tick, asking Buildbucket for the build status
[01:32:45.015] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:45.015] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:18:0) after 1m0s
[01:33:45.038] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:18:0)
[01:33:45.038] Timer tick, asking Buildbucket for the build status
[01:33:45.167] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:45.167] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:19:0) after 1m0s
[01:34:45.212] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:19:0)
[01:34:45.212] Timer tick, asking Buildbucket for the build status
[01:34:45.421] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:45.421] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:20:0) after 1m0s
[01:35:45.422] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:20:0)
[01:35:45.422] Timer tick, asking Buildbucket for the build status
[01:35:45.566] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:45.566] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:21:0) after 1m0s
[01:36:45.597] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:21:0)
[01:36:45.597] Timer tick, asking Buildbucket for the build status
[01:36:45.690] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:45.690] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:22:0) after 1m0s
[01:37:45.743] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:22:0)
[01:37:45.743] Timer tick, asking Buildbucket for the build status
[01:37:45.959] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:45.959] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:23:0) after 1m0s
[01:38:45.983] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:23:0)
[01:38:45.983] Timer tick, asking Buildbucket for the build status
[01:38:46.139] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:46.139] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:24:0) after 1m0s
[01:39:46.192] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:24:0)
[01:39:46.192] Timer tick, asking Buildbucket for the build status
[01:39:46.442] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:46.442] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:25:0) after 1m0s
[01:40:46.471] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:25:0)
[01:40:46.471] Timer tick, asking Buildbucket for the build status
[01:40:46.657] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:46.657] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:26:0) after 1m0s
[01:41:46.677] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:26:0)
[01:41:46.677] Timer tick, asking Buildbucket for the build status
[01:41:47.051] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:47.051] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:27:0) after 1m0s
[01:42:47.135] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:27:0)
[01:42:47.135] Timer tick, asking Buildbucket for the build status
[01:42:47.421] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:47.421] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:28:0) after 1m0s
[01:43:47.473] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:28:0)
[01:43:47.473] Timer tick, asking Buildbucket for the build status
[01:43:47.734] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:47.734] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:29:0) after 1m0s
[01:44:47.781] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:29:0)
[01:44:47.781] Timer tick, asking Buildbucket for the build status
[01:44:47.889] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:47.889] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:30:0) after 1m0s
[01:45:47.907] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:30:0)
[01:45:47.907] Timer tick, asking Buildbucket for the build status
[01:45:48.498] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:48.498] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:31:0) after 1m0s
[01:46:48.594] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:31:0)
[01:46:48.594] Timer tick, asking Buildbucket for the build status
[01:46:48.739] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:48.739] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:32:0) after 1m0s
[01:47:48.749] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:32:0)
[01:47:48.749] Timer tick, asking Buildbucket for the build status
[01:47:48.939] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:48.939] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:33:0) after 1m0s
[01:48:48.956] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:33:0)
[01:48:48.956] Timer tick, asking Buildbucket for the build status
[01:48:49.015] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:49.015] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:34:0) after 1m0s
[01:49:49.067] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:34:0)
[01:49:49.067] Timer tick, asking Buildbucket for the build status
[01:49:49.149] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:49.149] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:35:0) after 1m0s
[01:50:49.308] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:35:0)
[01:50:49.308] Timer tick, asking Buildbucket for the build status
[01:50:49.370] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:49.370] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:36:0) after 1m0s
[01:51:49.399] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:36:0)
[01:51:49.399] Timer tick, asking Buildbucket for the build status
[01:51:49.456] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:49.456] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:37:0) after 1m0s
[01:52:49.696] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:37:0)
[01:52:49.696] Timer tick, asking Buildbucket for the build status
[01:52:49.927] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:49.927] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:38:0) after 1m0s
[01:53:50.141] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:38:0)
[01:53:50.141] Timer tick, asking Buildbucket for the build status
[01:53:50.243] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:50.243] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:39:0) after 1m0s
[01:54:50.274] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:39:0)
[01:54:50.274] Timer tick, asking Buildbucket for the build status
[01:54:51.420] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:51.420] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:40:0) after 1m0s
[01:55:51.487] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:40:0)
[01:55:51.487] Timer tick, asking Buildbucket for the build status
[01:55:51.594] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:51.594] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:41:0) after 1m0s
[01:56:51.711] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:41:0)
[01:56:51.711] Timer tick, asking Buildbucket for the build status
[01:56:52.001] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:52.001] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:42:0) after 1m0s
[01:57:52.125] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:42:0)
[01:57:52.125] Timer tick, asking Buildbucket for the build status
[01:57:52.391] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:52.391] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:43:0) after 1m0s
[01:58:52.429] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:43:0)
[01:58:52.429] Timer tick, asking Buildbucket for the build status
[01:58:52.513] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:52.513] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:44:0) after 1m0s
[01:59:52.549] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:44:0)
[01:59:52.549] Timer tick, asking Buildbucket for the build status
[01:59:53.148] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:53.148] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:45:0) after 1m0s
[02:00:53.345] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:45:0)
[02:00:53.345] Timer tick, asking Buildbucket for the build status
[02:00:54.107] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:54.108] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:46:0) after 1m0s
[02:01:54.377] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:46:0)
[02:01:54.377] Timer tick, asking Buildbucket for the build status
[02:01:54.474] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:54.474] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:47:0) after 1m0s
[02:02:54.561] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:47:0)
[02:02:54.561] Timer tick, asking Buildbucket for the build status
[02:02:54.748] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:54.748] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:48:0) after 1m0s
[02:03:54.930] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:48:0)
[02:03:54.930] Timer tick, asking Buildbucket for the build status
[02:03:55.004] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:55.004] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:49:0) after 1m0s
[02:04:55.152] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:49:0)
[02:04:55.152] Timer tick, asking Buildbucket for the build status
[02:04:55.237] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:55.237] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:50:0) after 1m0s
[02:05:55.303] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:50:0)
[02:05:55.303] Timer tick, asking Buildbucket for the build status
[02:05:56.155] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:56.155] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:51:0) after 1m0s
[02:06:56.386] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:51:0)
[02:06:56.386] Timer tick, asking Buildbucket for the build status
[02:06:56.589] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:56.589] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:52:0) after 1m0s
[02:07:56.649] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:52:0)
[02:07:56.649] Timer tick, asking Buildbucket for the build status
[02:07:57.584] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:57.584] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:53:0) after 1m0s
[02:09:01.852] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:53:0)
[02:09:01.852] Timer tick, asking Buildbucket for the build status
[02:09:02.347] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:02.347] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:54:0) after 1m0s
[02:10:02.704] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:54:0)
[02:10:02.704] Timer tick, asking Buildbucket for the build status
[02:10:03.598] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:03.598] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:55:0) after 1m0s
[02:11:03.625] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:55:0)
[02:11:03.625] Timer tick, asking Buildbucket for the build status
[02:11:04.358] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:04.358] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:56:0) after 1m0s
[02:12:04.492] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:56:0)
[02:12:04.492] Timer tick, asking Buildbucket for the build status
[02:12:06.087] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:06.087] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:57:0) after 1m0s
[02:13:07.270] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:57:0)
[02:13:07.270] Timer tick, asking Buildbucket for the build status
[02:13:08.067] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:08.067] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:58:0) after 1m0s
[02:14:08.193] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:58:0)
[02:14:08.193] Timer tick, asking Buildbucket for the build status
[02:14:10.381] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:10.381] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:59:0) after 1m0s
[02:15:10.767] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:59:0)
[02:15:10.767] Timer tick, asking Buildbucket for the build status
[02:15:10.909] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:10.909] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:60:0) after 1m0s
[02:16:11.097] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:60:0)
[02:16:11.097] Timer tick, asking Buildbucket for the build status
[02:16:11.272] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:11.272] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:61:0) after 1m0s
[02:17:11.435] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:61:0)
[02:17:11.435] Timer tick, asking Buildbucket for the build status
[02:17:12.588] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:12.588] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:62:0) after 1m0s
[02:18:12.838] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:62:0)
[02:18:12.838] Timer tick, asking Buildbucket for the build status
[02:18:13.039] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:13.039] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:63:0) after 1m0s
[02:19:13.048] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:63:0)
[02:19:13.048] Timer tick, asking Buildbucket for the build status
[02:19:13.131] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:13.131] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:64:0) after 1m0s
[02:20:13.186] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:64:0)
[02:20:13.186] Timer tick, asking Buildbucket for the build status
[02:20:13.536] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:13.536] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:65:0) after 1m0s
[02:21:13.675] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:65:0)
[02:21:13.675] Timer tick, asking Buildbucket for the build status
[02:21:13.750] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:13.750] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:66:0) after 1m0s
[02:22:13.875] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:66:0)
[02:22:13.875] Timer tick, asking Buildbucket for the build status
[02:22:14.175] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:14.175] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:67:0) after 1m0s
[02:23:14.262] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:67:0)
[02:23:14.262] Timer tick, asking Buildbucket for the build status
[02:23:14.346] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:14.346] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:68:0) after 1m0s
[02:24:14.396] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:68:0)
[02:24:14.396] Timer tick, asking Buildbucket for the build status
[02:24:14.586] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:14.586] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:69:0) after 1m0s
[02:25:14.630] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:69:0)
[02:25:14.630] Timer tick, asking Buildbucket for the build status
[02:25:15.328] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:15.328] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:70:0) after 1m0s
[02:26:15.378] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:70:0)
[02:26:15.378] Timer tick, asking Buildbucket for the build status
[02:26:15.477] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:15.477] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:71:0) after 1m0s
[02:27:15.508] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:71:0)
[02:27:15.508] Timer tick, asking Buildbucket for the build status
[02:27:15.832] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:15.832] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:72:0) after 1m0s
[02:28:15.837] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:72:0)
[02:28:15.837] Timer tick, asking Buildbucket for the build status
[02:28:16.486] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:16.486] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:73:0) after 1m0s
[02:29:16.508] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:73:0)
[02:29:16.508] Timer tick, asking Buildbucket for the build status
[02:29:17.244] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:17.244] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:74:0) after 1m0s
[02:30:17.299] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:74:0)
[02:30:17.299] Timer tick, asking Buildbucket for the build status
[02:30:18.740] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:18.740] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:75:0) after 1m0s
[02:31:18.807] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:75:0)
[02:31:18.807] Timer tick, asking Buildbucket for the build status
[02:31:19.791] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:19.791] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:76:0) after 1m0s
[02:32:19.857] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:76:0)
[02:32:19.857] Timer tick, asking Buildbucket for the build status
[02:32:19.916] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:19.916] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:77:0) after 1m0s
[02:33:19.935] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:77:0)
[02:33:19.935] Timer tick, asking Buildbucket for the build status
[02:33:20.083] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:20.083] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:78:0) after 1m0s
[02:34:20.173] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:78:0)
[02:34:20.173] Timer tick, asking Buildbucket for the build status
[02:34:20.291] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:20.291] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:79:0) after 1m0s
[02:35:20.612] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:79:0)
[02:35:20.612] Timer tick, asking Buildbucket for the build status
[02:35:21.143] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:21.143] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:80:0) after 1m0s
[02:36:21.167] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:80:0)
[02:36:21.167] Timer tick, asking Buildbucket for the build status
[02:36:21.520] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:21.521] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:81:0) after 1m0s
[02:37:21.543] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:81:0)
[02:37:21.543] Timer tick, asking Buildbucket for the build status
[02:37:22.675] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:22.675] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:82:0) after 1m0s
[02:38:22.705] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:82:0)
[02:38:22.705] Timer tick, asking Buildbucket for the build status
[02:38:22.896] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:22.896] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:83:0) after 1m0s
[02:39:22.934] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:83:0)
[02:39:22.934] Timer tick, asking Buildbucket for the build status
[02:39:25.074] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:25.074] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:84:0) after 1m0s
[02:40:25.104] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:84:0)
[02:40:25.104] Timer tick, asking Buildbucket for the build status
[02:40:25.166] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:25.166] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:85:0) after 1m0s
[02:41:25.325] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:85:0)
[02:41:25.325] Timer tick, asking Buildbucket for the build status
[02:41:25.518] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:25.518] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:86:0) after 1m0s
[02:42:25.569] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:86:0)
[02:42:25.569] Timer tick, asking Buildbucket for the build status
[02:42:26.217] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:26.217] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:87:0) after 1m0s
[02:43:26.307] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:87:0)
[02:43:26.307] Timer tick, asking Buildbucket for the build status
[02:43:28.205] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:28.205] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:88:0) after 1m0s
[02:44:28.205] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:88:0)
[02:44:28.205] Timer tick, asking Buildbucket for the build status
[02:44:33.956] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:33.956] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:89:0) after 1m0s
[02:45:33.953] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:89:0)
[02:45:33.953] Timer tick, asking Buildbucket for the build status
[02:45:34.243] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:34.244] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:90:0) after 1m0s
[02:46:34.274] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:90:0)
[02:46:34.274] Timer tick, asking Buildbucket for the build status
[02:46:34.555] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:34.555] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:91:0) after 1m0s
[02:47:34.575] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:91:0)
[02:47:34.575] Timer tick, asking Buildbucket for the build status
[02:47:34.774] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:34.774] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:92:0) after 1m0s
[02:48:34.892] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:92:0)
[02:48:34.892] Timer tick, asking Buildbucket for the build status
[02:48:35.046] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:35.046] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:93:0) after 1m0s
[02:49:35.034] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:93:0)
[02:49:35.034] Timer tick, asking Buildbucket for the build status
[02:49:35.131] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:35.131] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:94:0) after 1m0s
[02:50:35.225] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:94:0)
[02:50:35.225] Timer tick, asking Buildbucket for the build status
[02:50:35.669] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:35.669] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:95:0) after 1m0s
[02:51:35.697] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:95:0)
[02:51:35.697] Timer tick, asking Buildbucket for the build status
[02:51:35.830] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:35.830] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:96:0) after 1m0s
[02:52:35.851] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:96:0)
[02:52:35.851] Timer tick, asking Buildbucket for the build status
[02:52:36.053] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:36.053] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:97:0) after 1m0s
[02:53:36.121] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:97:0)
[02:53:36.121] Timer tick, asking Buildbucket for the build status
[02:53:37.280] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:37.280] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:98:0) after 1m0s
[02:54:37.312] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:98:0)
[02:54:37.312] Timer tick, asking Buildbucket for the build status
[02:54:37.423] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:37.423] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:99:0) after 1m0s
[02:55:37.449] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:99:0)
[02:55:37.449] Timer tick, asking Buildbucket for the build status
[02:55:37.555] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:37.555] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:100:0) after 1m0s
[02:56:37.615] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:100:0)
[02:56:37.615] Timer tick, asking Buildbucket for the build status
[02:56:38.806] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:38.806] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:101:0) after 1m0s
[02:57:38.885] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:101:0)
[02:57:38.885] Timer tick, asking Buildbucket for the build status
[02:57:38.980] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:38.980] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:102:0) after 1m0s
[02:58:39.008] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:102:0)
[02:58:39.008] Timer tick, asking Buildbucket for the build status
[02:58:39.178] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:39.178] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:103:0) after 1m0s
[02:59:39.242] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:103:0)
[02:59:39.242] Timer tick, asking Buildbucket for the build status
[02:59:39.321] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:39.321] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:104:0) after 1m0s
[03:00:39.359] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:104:0)
[03:00:39.359] Timer tick, asking Buildbucket for the build status
[03:00:40.213] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:40.213] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:105:0) after 1m0s
[03:01:40.248] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:105:0)
[03:01:40.248] Timer tick, asking Buildbucket for the build status
[03:01:40.329] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:40.329] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:106:0) after 1m0s
[03:02:40.380] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:106:0)
[03:02:40.380] Timer tick, asking Buildbucket for the build status
[03:02:41.001] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:41.001] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:107:0) after 1m0s
[03:03:41.059] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:107:0)
[03:03:41.059] Timer tick, asking Buildbucket for the build status
[03:03:41.197] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:41.197] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:108:0) after 1m0s
[03:04:41.234] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:108:0)
[03:04:41.234] Timer tick, asking Buildbucket for the build status
[03:04:49.806] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:49.807] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:109:0) after 1m0s
[03:05:50.068] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:109:0)
[03:05:50.068] Timer tick, asking Buildbucket for the build status
[03:05:51.022] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:51.022] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:110:0) after 1m0s
[03:06:51.264] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:110:0)
[03:06:51.264] Timer tick, asking Buildbucket for the build status
[03:06:51.386] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:51.386] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:111:0) after 1m0s
[03:07:51.501] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:111:0)
[03:07:51.501] Timer tick, asking Buildbucket for the build status
[03:07:51.884] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:51.884] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:112:0) after 1m0s
[03:08:52.019] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:112:0)
[03:08:52.019] Timer tick, asking Buildbucket for the build status
[03:08:52.115] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:52.115] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:113:0) after 1m0s
[03:09:52.280] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:113:0)
[03:09:52.280] Timer tick, asking Buildbucket for the build status
[03:09:52.378] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:52.378] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:114:0) after 1m0s
[03:10:52.479] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:114:0)
[03:10:52.479] Timer tick, asking Buildbucket for the build status
[03:10:52.576] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:52.576] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:115:0) after 1m0s
[03:11:52.633] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:115:0)
[03:11:52.633] Timer tick, asking Buildbucket for the build status
[03:11:52.775] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:52.775] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:116:0) after 1m0s
[03:12:52.842] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:116:0)
[03:12:52.842] Timer tick, asking Buildbucket for the build status
[03:12:53.362] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:53.362] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:117:0) after 1m0s
[03:13:53.573] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:117:0)
[03:13:53.573] Timer tick, asking Buildbucket for the build status
[03:13:53.708] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:53.708] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:118:0) after 1m0s
[03:14:53.771] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:118:0)
[03:14:53.771] Timer tick, asking Buildbucket for the build status
[03:14:53.897] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:53.897] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:119:0) after 1m0s
[03:15:53.983] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:119:0)
[03:15:53.983] Timer tick, asking Buildbucket for the build status
[03:15:54.334] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:54.334] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:120:0) after 1m0s
[03:16:54.368] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:120:0)
[03:16:54.368] Timer tick, asking Buildbucket for the build status
[03:16:54.443] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:54.443] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:121:0) after 1m0s
[03:17:54.478] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:121:0)
[03:17:54.478] Timer tick, asking Buildbucket for the build status
[03:17:54.663] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:54.663] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:122:0) after 1m0s
[03:18:54.690] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:122:0)
[03:18:54.691] Timer tick, asking Buildbucket for the build status
[03:18:54.861] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:54.861] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:123:0) after 1m0s
[03:19:55.009] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:123:0)
[03:19:55.009] Timer tick, asking Buildbucket for the build status
[03:19:55.126] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:55.126] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:124:0) after 1m0s
[03:20:55.233] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:124:0)
[03:20:55.233] Timer tick, asking Buildbucket for the build status
[03:20:55.311] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:55.311] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:125:0) after 1m0s
[03:21:55.455] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:125:0)
[03:21:55.455] Timer tick, asking Buildbucket for the build status
[03:21:55.549] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:55.549] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:126:0) after 1m0s
[03:22:55.578] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:126:0)
[03:22:55.578] Timer tick, asking Buildbucket for the build status
[03:22:55.723] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:55.723] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:127:0) after 1m0s
[03:23:55.920] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:127:0)
[03:23:55.920] Timer tick, asking Buildbucket for the build status
[03:23:56.171] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:56.171] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:128:0) after 1m0s
[03:24:56.266] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:128:0)
[03:24:56.266] Timer tick, asking Buildbucket for the build status
[03:24:56.461] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:56.461] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:129:0) after 1m0s
[03:25:56.535] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:129:0)
[03:25:56.535] Timer tick, asking Buildbucket for the build status
[03:25:56.685] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:56.685] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:130:0) after 1m0s
[03:26:56.928] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:130:0)
[03:26:56.928] Timer tick, asking Buildbucket for the build status
[03:26:57.406] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:57.406] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:131:0) after 1m0s
[03:27:57.457] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:131:0)
[03:27:57.457] Timer tick, asking Buildbucket for the build status
[03:28:01.032] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:01.032] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:132:0) after 1m0s
[03:29:01.367] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:132:0)
[03:29:01.367] Timer tick, asking Buildbucket for the build status
[03:29:01.528] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:01.528] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:133:0) after 1m0s
[03:30:02.121] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:133:0)
[03:30:02.121] Timer tick, asking Buildbucket for the build status
[03:30:03.552] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:03.552] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:134:0) after 1m0s
[03:31:03.617] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:134:0)
[03:31:03.617] Timer tick, asking Buildbucket for the build status
[03:31:03.752] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:03.752] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:135:0) after 1m0s
[03:32:03.797] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:135:0)
[03:32:03.797] Timer tick, asking Buildbucket for the build status
[03:32:04.189] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:04.189] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:136:0) after 1m0s
[03:33:04.247] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:136:0)
[03:33:04.247] Timer tick, asking Buildbucket for the build status
[03:33:04.471] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:04.471] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:137:0) after 1m0s
[03:34:04.614] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:137:0)
[03:34:04.614] Timer tick, asking Buildbucket for the build status
[03:34:04.847] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:04.847] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:138:0) after 1m0s
[03:35:04.931] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:138:0)
[03:35:04.931] Timer tick, asking Buildbucket for the build status
[03:35:05.065] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:05.065] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:139:0) after 1m0s
[03:36:05.107] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:139:0)
[03:36:05.107] Timer tick, asking Buildbucket for the build status
[03:36:05.191] Build 8861613463998502192: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:05.191] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027043525921500832:140:0) after 1m0s
[03:36:38.521] Received PubSub notification, asking Buildbucket for the build status
[03:36:38.903] Build 8861613463998502192: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:36:38.903] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607312197875885",
"created_by": "project:chromium",
"created_ts": "1607303859672958",
"id": "8861613463998502192",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"42c20055796bbb6fa632851e20887f23d0e482bf\"}}",
"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\": \"42c20055796bbb6fa632851e20887f23d0e482bf\", \"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-9t2s\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win32-official\", \"buildnumber\": 1068, \"got_angle_revision\": \"251ba5cb119ff2fed0e861cbc9b096c45004c1fa\", \"got_dawn_revision\": \"d63d562d1b26e01b369d9a58eddeaccfaa688a02\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"42c20055796bbb6fa632851e20887f23d0e482bf\", \"got_revision_cp\": \"refs/heads/master@{#834094}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"7c84426d2abe231f3087a2942fd831137eb3b0aa\", \"got_v8_revision\": \"61f3e61408129afc0d9e1268d3354a283ea3c881\", \"got_v8_revision_cp\": \"refs/heads/8.9.100@{#1}\", \"got_webrtc_revision\": \"393159147685c9294e423eb8b2bdc92dc78acfd4\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32780}\", \"mirrored_builders\": [\"tryserver.chromium:win32-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"42c20055796bbb6fa632851e20887f23d0e482bf\"}, \"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-1-9t2s\"], \"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\": [\"5640-d0eb544\"], \"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": "1607303899241043",
"status": "COMPLETED",
"status_changed_ts": "1607312198293667",
"tags": [
"build_address:luci.chromium.ci/win32-official/1068",
"builder:win32-official",
"buildset:commit/git/42c20055796bbb6fa632851e20887f23d0e482bf",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/42c20055796bbb6fa632851e20887f23d0e482bf",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027043525921500832",
"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/8861613463998502192/+/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:5053981883e21210",
"user_agent:luci-scheduler"
],
"updated_ts": "1607312198324973",
"url": "https://ci.chromium.org/b/8861613463998502192",
"utcnow_ts": "1607312198874834"
}
}
[03:36:38.906] Invocation finished in 2h19m0.451267396s with status SUCCEEDED