[06:11:51.190] New invocation is queued and will start shortly
[06:11:52.274] Starting the invocation (attempt 1)
[06:11:52.344] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:11:52.345] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:11:52.345] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022404570416572720",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d33e6f5fdac171fca916c06a6cb6d3445e937f76\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9022404570416572720",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/d33e6f5fdac171fca916c06a6cb6d3445e937f76",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d33e6f5fdac171fca916c06a6cb6d3445e937f76",
"gitiles_ref:refs/heads/master"
]
}
[06:11:53.963] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611727912521398",
"id": "8856974508359053008",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d33e6f5fdac171fca916c06a6cb6d3445e937f76\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611727913588225",
"tags": [
"build_address:luci.chromium.ci/win-official/1473",
"builder:win-official",
"buildset:commit/git/d33e6f5fdac171fca916c06a6cb6d3445e937f76",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d33e6f5fdac171fca916c06a6cb6d3445e937f76",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022404570416572720",
"scheduler_job_id:chromium/win-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8856974508359053008/+/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": "1611727913588401",
"url": "https://ci.chromium.org/b/8856974508359053008",
"utcnow_ts": "1611727913933238"
}
}
[06:11:53.963] Task URL: https://ci.chromium.org/b/8856974508359053008
[06:11:53.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:2:0) after 1m0s
[06:12:41.673] Received PubSub notification, asking Buildbucket for the build status
[06:12:42.279] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:53.983] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:2:0)
[06:12:53.983] Timer tick, asking Buildbucket for the build status
[06:12:54.038] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:54.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:4:0) after 1m0s
[06:13:54.059] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:4:0)
[06:13:54.059] Timer tick, asking Buildbucket for the build status
[06:13:54.161] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:54.161] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:5:0) after 1m0s
[06:14:54.247] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:5:0)
[06:14:54.247] Timer tick, asking Buildbucket for the build status
[06:14:54.319] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:54.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:6:0) after 1m0s
[06:15:54.337] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:6:0)
[06:15:54.337] Timer tick, asking Buildbucket for the build status
[06:15:54.430] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:54.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:7:0) after 1m0s
[06:16:54.601] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:7:0)
[06:16:54.601] Timer tick, asking Buildbucket for the build status
[06:16:54.726] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:54.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:8:0) after 1m0s
[06:17:54.805] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:8:0)
[06:17:54.805] Timer tick, asking Buildbucket for the build status
[06:17:55.050] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:55.050] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:9:0) after 1m0s
[06:18:55.099] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:9:0)
[06:18:55.099] Timer tick, asking Buildbucket for the build status
[06:18:55.188] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:55.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:10:0) after 1m0s
[06:19:55.268] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:10:0)
[06:19:55.268] Timer tick, asking Buildbucket for the build status
[06:19:55.517] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:55.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:11:0) after 1m0s
[06:20:55.555] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:11:0)
[06:20:55.555] Timer tick, asking Buildbucket for the build status
[06:20:55.664] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:55.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:12:0) after 1m0s
[06:21:55.981] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:12:0)
[06:21:55.981] Timer tick, asking Buildbucket for the build status
[06:21:56.071] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:56.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:13:0) after 1m0s
[06:22:56.183] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:13:0)
[06:22:56.183] Timer tick, asking Buildbucket for the build status
[06:22:56.357] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:56.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:14:0) after 1m0s
[06:23:56.482] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:14:0)
[06:23:56.482] Timer tick, asking Buildbucket for the build status
[06:23:56.711] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:56.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:15:0) after 1m0s
[06:24:56.748] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:15:0)
[06:24:56.748] Timer tick, asking Buildbucket for the build status
[06:24:56.851] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:56.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:16:0) after 1m0s
[06:25:56.833] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:16:0)
[06:25:56.833] Timer tick, asking Buildbucket for the build status
[06:25:56.897] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:56.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:17:0) after 1m0s
[06:26:57.309] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:17:0)
[06:26:57.309] Timer tick, asking Buildbucket for the build status
[06:26:58.779] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:58.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:18:0) after 1m0s
[06:27:58.961] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:18:0)
[06:27:58.961] Timer tick, asking Buildbucket for the build status
[06:27:59.043] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:59.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:19:0) after 1m0s
[06:28:59.058] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:19:0)
[06:28:59.058] Timer tick, asking Buildbucket for the build status
[06:28:59.581] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:59.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:20:0) after 1m0s
[06:29:59.713] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:20:0)
[06:29:59.713] Timer tick, asking Buildbucket for the build status
[06:29:59.781] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:59.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:21:0) after 1m0s
[06:30:59.875] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:21:0)
[06:30:59.875] Timer tick, asking Buildbucket for the build status
[06:30:59.926] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:59.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:22:0) after 1m0s
[06:32:00.001] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:22:0)
[06:32:00.001] Timer tick, asking Buildbucket for the build status
[06:32:00.145] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:00.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:23:0) after 1m0s
[06:33:00.229] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:23:0)
[06:33:00.229] Timer tick, asking Buildbucket for the build status
[06:33:00.294] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:00.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:24:0) after 1m0s
[06:34:00.382] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:24:0)
[06:34:00.383] Timer tick, asking Buildbucket for the build status
[06:34:00.547] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:00.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:25:0) after 1m0s
[06:35:01.038] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:25:0)
[06:35:01.038] Timer tick, asking Buildbucket for the build status
[06:35:01.227] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:01.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:26:0) after 1m0s
[06:36:01.366] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:26:0)
[06:36:01.366] Timer tick, asking Buildbucket for the build status
[06:36:02.864] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:02.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:27:0) after 1m0s
[06:37:02.865] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:27:0)
[06:37:02.865] Timer tick, asking Buildbucket for the build status
[06:37:03.000] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:03.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:28:0) after 1m0s
[06:38:03.048] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:28:0)
[06:38:03.048] Timer tick, asking Buildbucket for the build status
[06:38:03.106] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:03.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:29:0) after 1m0s
[06:39:03.133] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:29:0)
[06:39:03.133] Timer tick, asking Buildbucket for the build status
[06:39:03.486] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:03.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:30:0) after 1m0s
[06:40:03.569] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:30:0)
[06:40:03.569] Timer tick, asking Buildbucket for the build status
[06:40:03.762] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:03.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:31:0) after 1m0s
[06:41:03.995] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:31:0)
[06:41:03.995] Timer tick, asking Buildbucket for the build status
[06:41:04.067] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:04.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:32:0) after 1m0s
[06:42:04.275] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:32:0)
[06:42:04.275] Timer tick, asking Buildbucket for the build status
[06:42:04.376] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:04.376] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:33:0) after 1m0s
[06:43:04.530] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:33:0)
[06:43:04.530] Timer tick, asking Buildbucket for the build status
[06:43:04.833] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:04.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:34:0) after 1m0s
[06:44:05.143] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:34:0)
[06:44:05.143] Timer tick, asking Buildbucket for the build status
[06:44:05.484] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:05.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:35:0) after 1m0s
[06:45:05.567] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:35:0)
[06:45:05.567] Timer tick, asking Buildbucket for the build status
[06:45:05.742] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:05.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:36:0) after 1m0s
[06:46:05.798] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:36:0)
[06:46:05.798] Timer tick, asking Buildbucket for the build status
[06:46:05.879] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:05.879] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:37:0) after 1m0s
[06:47:05.935] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:37:0)
[06:47:05.935] Timer tick, asking Buildbucket for the build status
[06:47:06.042] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:06.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:38:0) after 1m0s
[06:48:06.098] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:38:0)
[06:48:06.098] Timer tick, asking Buildbucket for the build status
[06:48:06.256] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:06.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:39:0) after 1m0s
[06:49:06.488] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:39:0)
[06:49:06.488] Timer tick, asking Buildbucket for the build status
[06:49:06.625] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:06.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:40:0) after 1m0s
[06:50:06.780] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:40:0)
[06:50:06.780] Timer tick, asking Buildbucket for the build status
[06:50:07.614] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:07.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:41:0) after 1m0s
[06:51:07.634] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:41:0)
[06:51:07.634] Timer tick, asking Buildbucket for the build status
[06:51:07.705] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:07.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:42:0) after 1m0s
[06:52:07.867] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:42:0)
[06:52:07.867] Timer tick, asking Buildbucket for the build status
[06:52:08.004] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:08.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:43:0) after 1m0s
[06:53:08.117] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:43:0)
[06:53:08.117] Timer tick, asking Buildbucket for the build status
[06:53:08.183] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:08.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:44:0) after 1m0s
[06:54:08.205] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:44:0)
[06:54:08.205] Timer tick, asking Buildbucket for the build status
[06:54:08.349] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:08.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:45:0) after 1m0s
[06:55:08.436] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:45:0)
[06:55:08.436] Timer tick, asking Buildbucket for the build status
[06:55:08.511] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:08.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:46:0) after 1m0s
[06:56:08.964] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:46:0)
[06:56:08.964] Timer tick, asking Buildbucket for the build status
[06:56:09.035] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:09.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:47:0) after 1m0s
[06:57:09.258] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:47:0)
[06:57:09.258] Timer tick, asking Buildbucket for the build status
[06:57:09.371] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:09.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:48:0) after 1m0s
[06:58:09.394] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:48:0)
[06:58:09.394] Timer tick, asking Buildbucket for the build status
[06:58:09.487] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:09.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:49:0) after 1m0s
[06:59:09.605] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:49:0)
[06:59:09.605] Timer tick, asking Buildbucket for the build status
[06:59:09.672] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:09.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:50:0) after 1m0s
[07:00:09.993] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:50:0)
[07:00:09.993] Timer tick, asking Buildbucket for the build status
[07:00:10.135] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:10.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:51:0) after 1m0s
[07:01:11.069] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:51:0)
[07:01:11.069] Timer tick, asking Buildbucket for the build status
[07:01:11.167] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:11.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:52:0) after 1m0s
[07:02:11.230] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:52:0)
[07:02:11.230] Timer tick, asking Buildbucket for the build status
[07:02:11.382] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:11.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:53:0) after 1m0s
[07:03:11.406] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:53:0)
[07:03:11.406] Timer tick, asking Buildbucket for the build status
[07:03:11.478] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:11.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:54:0) after 1m0s
[07:04:11.547] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:54:0)
[07:04:11.547] Timer tick, asking Buildbucket for the build status
[07:04:11.634] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:11.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:55:0) after 1m0s
[07:05:11.756] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:55:0)
[07:05:11.756] Timer tick, asking Buildbucket for the build status
[07:05:11.816] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:11.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:56:0) after 1m0s
[07:06:11.856] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:56:0)
[07:06:11.856] Timer tick, asking Buildbucket for the build status
[07:06:11.921] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:11.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:57:0) after 1m0s
[07:07:11.925] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:57:0)
[07:07:11.925] Timer tick, asking Buildbucket for the build status
[07:07:12.059] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:12.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:58:0) after 1m0s
[07:08:12.194] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:58:0)
[07:08:12.194] Timer tick, asking Buildbucket for the build status
[07:08:12.267] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:12.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:59:0) after 1m0s
[07:09:12.352] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:59:0)
[07:09:12.352] Timer tick, asking Buildbucket for the build status
[07:09:12.422] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:12.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:60:0) after 1m0s
[07:10:12.580] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:60:0)
[07:10:12.580] Timer tick, asking Buildbucket for the build status
[07:10:12.667] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:12.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:61:0) after 1m0s
[07:11:12.820] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:61:0)
[07:11:12.820] Timer tick, asking Buildbucket for the build status
[07:11:12.937] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:12.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:62:0) after 1m0s
[07:12:13.023] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:62:0)
[07:12:13.023] Timer tick, asking Buildbucket for the build status
[07:12:13.106] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:13.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:63:0) after 1m0s
[07:13:13.173] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:63:0)
[07:13:13.173] Timer tick, asking Buildbucket for the build status
[07:13:13.283] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:13.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:64:0) after 1m0s
[07:14:13.270] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:64:0)
[07:14:13.270] Timer tick, asking Buildbucket for the build status
[07:14:13.467] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:13.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:65:0) after 1m0s
[07:15:13.529] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:65:0)
[07:15:13.529] Timer tick, asking Buildbucket for the build status
[07:15:13.586] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:13.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:66:0) after 1m0s
[07:16:13.653] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:66:0)
[07:16:13.653] Timer tick, asking Buildbucket for the build status
[07:16:13.721] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:13.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:67:0) after 1m0s
[07:17:13.715] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:67:0)
[07:17:13.715] Timer tick, asking Buildbucket for the build status
[07:17:13.822] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:13.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:68:0) after 1m0s
[07:18:13.871] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:68:0)
[07:18:13.871] Timer tick, asking Buildbucket for the build status
[07:18:13.937] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:13.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:69:0) after 1m0s
[07:19:14.038] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:69:0)
[07:19:14.038] Timer tick, asking Buildbucket for the build status
[07:19:14.091] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:14.091] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:70:0) after 1m0s
[07:20:14.208] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:70:0)
[07:20:14.208] Timer tick, asking Buildbucket for the build status
[07:20:14.277] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:14.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:71:0) after 1m0s
[07:21:14.359] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:71:0)
[07:21:14.359] Timer tick, asking Buildbucket for the build status
[07:21:14.454] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:14.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:72:0) after 1m0s
[07:22:14.512] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:72:0)
[07:22:14.512] Timer tick, asking Buildbucket for the build status
[07:22:14.636] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:14.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:73:0) after 1m0s
[07:23:14.744] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:73:0)
[07:23:14.744] Timer tick, asking Buildbucket for the build status
[07:23:14.816] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:14.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:74:0) after 1m0s
[07:24:14.919] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:74:0)
[07:24:14.919] Timer tick, asking Buildbucket for the build status
[07:24:15.455] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:15.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:75:0) after 1m0s
[07:25:15.520] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:75:0)
[07:25:15.520] Timer tick, asking Buildbucket for the build status
[07:25:15.571] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:15.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:76:0) after 1m0s
[07:26:15.661] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:76:0)
[07:26:15.661] Timer tick, asking Buildbucket for the build status
[07:26:15.795] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:15.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:77:0) after 1m0s
[07:27:15.823] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:77:0)
[07:27:15.823] Timer tick, asking Buildbucket for the build status
[07:27:16.432] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:16.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:78:0) after 1m0s
[07:28:16.447] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:78:0)
[07:28:16.447] Timer tick, asking Buildbucket for the build status
[07:28:16.623] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:16.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:79:0) after 1m0s
[07:29:16.635] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:79:0)
[07:29:16.635] Timer tick, asking Buildbucket for the build status
[07:29:16.706] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:16.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:80:0) after 1m0s
[07:30:16.806] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:80:0)
[07:30:16.806] Timer tick, asking Buildbucket for the build status
[07:30:16.966] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:16.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:81:0) after 1m0s
[07:31:16.987] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:81:0)
[07:31:16.987] Timer tick, asking Buildbucket for the build status
[07:31:17.311] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:17.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:82:0) after 1m0s
[07:32:17.358] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:82:0)
[07:32:17.358] Timer tick, asking Buildbucket for the build status
[07:32:17.458] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:17.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:83:0) after 1m0s
[07:33:17.550] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:83:0)
[07:33:17.550] Timer tick, asking Buildbucket for the build status
[07:33:17.623] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:17.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:84:0) after 1m0s
[07:34:17.761] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:84:0)
[07:34:17.761] Timer tick, asking Buildbucket for the build status
[07:34:18.773] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:18.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:85:0) after 1m0s
[07:35:18.777] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:85:0)
[07:35:18.777] Timer tick, asking Buildbucket for the build status
[07:35:19.111] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:19.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:86:0) after 1m0s
[07:36:19.146] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:86:0)
[07:36:19.146] Timer tick, asking Buildbucket for the build status
[07:36:19.219] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:19.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:87:0) after 1m0s
[07:37:19.250] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:87:0)
[07:37:19.250] Timer tick, asking Buildbucket for the build status
[07:37:19.378] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:19.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:88:0) after 1m0s
[07:38:19.388] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:88:0)
[07:38:19.388] Timer tick, asking Buildbucket for the build status
[07:38:19.447] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:19.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:89:0) after 1m0s
[07:39:19.470] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:89:0)
[07:39:19.470] Timer tick, asking Buildbucket for the build status
[07:39:19.606] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:19.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:90:0) after 1m0s
[07:40:19.627] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:90:0)
[07:40:19.627] Timer tick, asking Buildbucket for the build status
[07:40:19.680] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:19.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:91:0) after 1m0s
[07:41:19.924] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:91:0)
[07:41:19.924] Timer tick, asking Buildbucket for the build status
[07:41:20.001] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:20.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:92:0) after 1m0s
[07:42:20.031] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:92:0)
[07:42:20.031] Timer tick, asking Buildbucket for the build status
[07:42:20.179] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:20.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:93:0) after 1m0s
[07:43:20.227] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:93:0)
[07:43:20.227] Timer tick, asking Buildbucket for the build status
[07:43:20.280] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:20.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:94:0) after 1m0s
[07:44:20.331] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:94:0)
[07:44:20.331] Timer tick, asking Buildbucket for the build status
[07:44:20.437] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:20.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:95:0) after 1m0s
[07:45:20.473] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:95:0)
[07:45:20.473] Timer tick, asking Buildbucket for the build status
[07:45:20.531] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:20.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:96:0) after 1m0s
[07:46:20.561] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:96:0)
[07:46:20.561] Timer tick, asking Buildbucket for the build status
[07:46:20.645] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:20.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:97:0) after 1m0s
[07:47:20.748] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:97:0)
[07:47:20.748] Timer tick, asking Buildbucket for the build status
[07:47:20.857] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:20.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:98:0) after 1m0s
[07:48:20.879] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:98:0)
[07:48:20.879] Timer tick, asking Buildbucket for the build status
[07:48:20.994] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:20.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:99:0) after 1m0s
[07:49:21.072] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:99:0)
[07:49:21.072] Timer tick, asking Buildbucket for the build status
[07:49:21.133] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:21.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:100:0) after 1m0s
[07:50:21.178] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:100:0)
[07:50:21.179] Timer tick, asking Buildbucket for the build status
[07:50:21.245] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:21.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:101:0) after 1m0s
[07:51:21.259] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:101:0)
[07:51:21.259] Timer tick, asking Buildbucket for the build status
[07:51:21.343] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:21.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:102:0) after 1m0s
[07:52:21.429] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:102:0)
[07:52:21.429] Timer tick, asking Buildbucket for the build status
[07:52:21.982] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:21.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:103:0) after 1m0s
[07:53:22.114] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:103:0)
[07:53:22.114] Timer tick, asking Buildbucket for the build status
[07:53:22.201] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:22.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:104:0) after 1m0s
[07:54:22.266] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:104:0)
[07:54:22.266] Timer tick, asking Buildbucket for the build status
[07:54:22.315] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:22.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:105:0) after 1m0s
[07:55:22.339] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:105:0)
[07:55:22.339] Timer tick, asking Buildbucket for the build status
[07:55:22.387] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:22.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:106:0) after 1m0s
[07:56:22.397] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:106:0)
[07:56:22.397] Timer tick, asking Buildbucket for the build status
[07:56:22.455] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:22.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:107:0) after 1m0s
[07:57:22.455] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:107:0)
[07:57:22.455] Timer tick, asking Buildbucket for the build status
[07:57:23.185] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:23.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:108:0) after 1m0s
[07:58:23.230] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:108:0)
[07:58:23.230] Timer tick, asking Buildbucket for the build status
[07:58:23.307] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:23.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:109:0) after 1m0s
[07:59:23.357] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:109:0)
[07:59:23.357] Timer tick, asking Buildbucket for the build status
[07:59:23.415] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:23.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:110:0) after 1m0s
[08:00:23.418] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:110:0)
[08:00:23.418] Timer tick, asking Buildbucket for the build status
[08:00:23.542] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:23.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:111:0) after 1m0s
[08:01:23.809] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:111:0)
[08:01:23.809] Timer tick, asking Buildbucket for the build status
[08:01:24.262] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:24.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:112:0) after 1m0s
[08:02:24.307] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:112:0)
[08:02:24.307] Timer tick, asking Buildbucket for the build status
[08:02:24.479] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:24.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:113:0) after 1m0s
[08:03:24.532] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:113:0)
[08:03:24.532] Timer tick, asking Buildbucket for the build status
[08:03:27.395] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:27.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:114:0) after 1m0s
[08:04:27.425] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:114:0)
[08:04:27.425] Timer tick, asking Buildbucket for the build status
[08:04:27.493] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:27.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:115:0) after 1m0s
[08:05:27.621] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:115:0)
[08:05:27.621] Timer tick, asking Buildbucket for the build status
[08:05:27.691] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:27.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:116:0) after 1m0s
[08:06:27.751] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:116:0)
[08:06:27.751] Timer tick, asking Buildbucket for the build status
[08:06:27.857] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:27.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:117:0) after 1m0s
[08:07:27.918] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:117:0)
[08:07:27.918] Timer tick, asking Buildbucket for the build status
[08:07:28.065] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:28.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:118:0) after 1m0s
[08:08:28.051] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:118:0)
[08:08:28.051] Timer tick, asking Buildbucket for the build status
[08:08:28.390] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:28.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:119:0) after 1m0s
[08:09:28.464] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:119:0)
[08:09:28.465] Timer tick, asking Buildbucket for the build status
[08:09:28.541] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:28.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:120:0) after 1m0s
[08:10:28.592] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:120:0)
[08:10:28.592] Timer tick, asking Buildbucket for the build status
[08:10:28.663] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:28.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:121:0) after 1m0s
[08:11:28.807] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:121:0)
[08:11:28.807] Timer tick, asking Buildbucket for the build status
[08:11:28.867] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:28.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:122:0) after 1m0s
[08:12:29.218] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:122:0)
[08:12:29.218] Timer tick, asking Buildbucket for the build status
[08:12:29.834] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:29.834] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:123:0) after 1m0s
[08:13:29.965] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:123:0)
[08:13:29.965] Timer tick, asking Buildbucket for the build status
[08:13:30.100] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:30.100] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:124:0) after 1m0s
[08:14:30.187] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:124:0)
[08:14:30.187] Timer tick, asking Buildbucket for the build status
[08:14:30.282] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:30.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:125:0) after 1m0s
[08:15:30.305] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:125:0)
[08:15:30.305] Timer tick, asking Buildbucket for the build status
[08:15:30.579] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:30.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:126:0) after 1m0s
[08:16:30.628] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:126:0)
[08:16:30.628] Timer tick, asking Buildbucket for the build status
[08:16:30.702] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:30.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:127:0) after 1m0s
[08:17:30.729] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:127:0)
[08:17:30.729] Timer tick, asking Buildbucket for the build status
[08:17:30.848] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:30.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:128:0) after 1m0s
[08:18:30.892] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:128:0)
[08:18:30.892] Timer tick, asking Buildbucket for the build status
[08:18:31.033] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:31.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:129:0) after 1m0s
[08:19:31.076] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:129:0)
[08:19:31.076] Timer tick, asking Buildbucket for the build status
[08:19:31.311] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:31.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:130:0) after 1m0s
[08:20:31.350] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:130:0)
[08:20:31.350] Timer tick, asking Buildbucket for the build status
[08:20:31.422] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:31.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:131:0) after 1m0s
[08:21:31.420] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:131:0)
[08:21:31.420] Timer tick, asking Buildbucket for the build status
[08:21:31.481] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:31.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:132:0) after 1m0s
[08:22:31.552] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:132:0)
[08:22:31.552] Timer tick, asking Buildbucket for the build status
[08:22:31.908] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:31.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:133:0) after 1m0s
[08:23:32.148] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:133:0)
[08:23:32.148] Timer tick, asking Buildbucket for the build status
[08:23:32.416] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:32.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:134:0) after 1m0s
[08:24:32.739] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:134:0)
[08:24:32.739] Timer tick, asking Buildbucket for the build status
[08:24:32.843] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:32.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:135:0) after 1m0s
[08:25:33.007] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:135:0)
[08:25:33.007] Timer tick, asking Buildbucket for the build status
[08:25:33.082] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:33.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:136:0) after 1m0s
[08:26:33.386] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:136:0)
[08:26:33.387] Timer tick, asking Buildbucket for the build status
[08:26:33.768] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:33.768] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:137:0) after 1m0s
[08:27:33.962] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:137:0)
[08:27:33.962] Timer tick, asking Buildbucket for the build status
[08:27:34.368] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:34.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:138:0) after 1m0s
[08:28:34.394] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:138:0)
[08:28:34.394] Timer tick, asking Buildbucket for the build status
[08:28:34.706] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:34.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:139:0) after 1m0s
[08:29:34.775] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:139:0)
[08:29:34.775] Timer tick, asking Buildbucket for the build status
[08:29:34.865] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:34.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:140:0) after 1m0s
[08:30:35.176] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:140:0)
[08:30:35.176] Timer tick, asking Buildbucket for the build status
[08:30:35.744] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:35.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:141:0) after 1m0s
[08:31:35.789] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:141:0)
[08:31:35.789] Timer tick, asking Buildbucket for the build status
[08:31:38.330] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:38.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:142:0) after 1m0s
[08:32:38.372] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:142:0)
[08:32:38.372] Timer tick, asking Buildbucket for the build status
[08:32:39.473] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:39.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:143:0) after 1m0s
[08:33:39.531] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:143:0)
[08:33:39.531] Timer tick, asking Buildbucket for the build status
[08:33:39.801] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:39.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:144:0) after 1m0s
[08:34:39.822] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:144:0)
[08:34:39.822] Timer tick, asking Buildbucket for the build status
[08:34:40.189] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:40.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:145:0) after 1m0s
[08:35:40.265] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:145:0)
[08:35:40.265] Timer tick, asking Buildbucket for the build status
[08:35:40.344] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:40.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:146:0) after 1m0s
[08:36:40.391] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:146:0)
[08:36:40.391] Timer tick, asking Buildbucket for the build status
[08:36:40.579] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:40.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:147:0) after 1m0s
[08:37:40.607] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:147:0)
[08:37:40.607] Timer tick, asking Buildbucket for the build status
[08:37:40.849] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:40.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:148:0) after 1m0s
[08:38:40.849] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:148:0)
[08:38:40.849] Timer tick, asking Buildbucket for the build status
[08:38:41.116] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:41.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:149:0) after 1m0s
[08:39:41.103] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:149:0)
[08:39:41.103] Timer tick, asking Buildbucket for the build status
[08:39:41.310] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:41.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:150:0) after 1m0s
[08:40:41.310] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:150:0)
[08:40:41.310] Timer tick, asking Buildbucket for the build status
[08:40:41.373] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:41.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:151:0) after 1m0s
[08:41:41.470] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:151:0)
[08:41:41.470] Timer tick, asking Buildbucket for the build status
[08:41:41.567] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:41.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:152:0) after 1m0s
[08:42:41.574] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:152:0)
[08:42:41.574] Timer tick, asking Buildbucket for the build status
[08:42:41.755] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:41.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:153:0) after 1m0s
[08:43:41.809] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:153:0)
[08:43:41.809] Timer tick, asking Buildbucket for the build status
[08:43:41.878] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:41.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:154:0) after 1m0s
[08:44:41.911] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:154:0)
[08:44:41.911] Timer tick, asking Buildbucket for the build status
[08:44:42.036] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:42.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:155:0) after 1m0s
[08:45:42.144] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:155:0)
[08:45:42.144] Timer tick, asking Buildbucket for the build status
[08:45:42.206] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:42.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:156:0) after 1m0s
[08:46:42.309] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:156:0)
[08:46:42.309] Timer tick, asking Buildbucket for the build status
[08:46:42.378] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:42.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:157:0) after 1m0s
[08:47:42.401] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:157:0)
[08:47:42.401] Timer tick, asking Buildbucket for the build status
[08:47:42.680] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:42.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:158:0) after 1m0s
[08:48:42.719] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:158:0)
[08:48:42.719] Timer tick, asking Buildbucket for the build status
[08:48:42.933] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:42.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:159:0) after 1m0s
[08:49:42.971] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:159:0)
[08:49:42.971] Timer tick, asking Buildbucket for the build status
[08:49:43.144] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:43.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:160:0) after 1m0s
[08:50:43.239] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:160:0)
[08:50:43.239] Timer tick, asking Buildbucket for the build status
[08:50:43.295] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:43.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:161:0) after 1m0s
[08:51:43.564] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:161:0)
[08:51:43.564] Timer tick, asking Buildbucket for the build status
[08:51:43.655] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:43.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:162:0) after 1m0s
[08:52:43.701] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:162:0)
[08:52:43.701] Timer tick, asking Buildbucket for the build status
[08:52:43.991] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:43.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:163:0) after 1m0s
[08:53:44.051] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:163:0)
[08:53:44.051] Timer tick, asking Buildbucket for the build status
[08:53:44.162] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:44.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:164:0) after 1m0s
[08:54:44.436] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:164:0)
[08:54:44.436] Timer tick, asking Buildbucket for the build status
[08:54:44.664] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:44.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:165:0) after 1m0s
[08:55:44.655] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:165:0)
[08:55:44.655] Timer tick, asking Buildbucket for the build status
[08:55:44.728] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:44.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:166:0) after 1m0s
[08:56:44.785] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:166:0)
[08:56:44.785] Timer tick, asking Buildbucket for the build status
[08:56:44.849] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:44.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:167:0) after 1m0s
[08:57:44.952] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:167:0)
[08:57:44.952] Timer tick, asking Buildbucket for the build status
[08:57:45.070] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:45.070] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:168:0) after 1m0s
[08:58:45.128] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:168:0)
[08:58:45.128] Timer tick, asking Buildbucket for the build status
[08:58:45.346] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:45.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:169:0) after 1m0s
[08:59:45.594] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:169:0)
[08:59:45.594] Timer tick, asking Buildbucket for the build status
[08:59:45.651] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:45.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:170:0) after 1m0s
[09:00:45.773] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:170:0)
[09:00:45.773] Timer tick, asking Buildbucket for the build status
[09:00:45.913] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:45.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:171:0) after 1m0s
[09:01:45.937] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:171:0)
[09:01:45.937] Timer tick, asking Buildbucket for the build status
[09:01:45.996] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:45.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:172:0) after 1m0s
[09:02:46.039] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:172:0)
[09:02:46.040] Timer tick, asking Buildbucket for the build status
[09:02:46.171] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:46.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:173:0) after 1m0s
[09:03:46.464] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:173:0)
[09:03:46.464] Timer tick, asking Buildbucket for the build status
[09:03:46.537] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:46.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:174:0) after 1m0s
[09:04:47.078] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:174:0)
[09:04:47.078] Timer tick, asking Buildbucket for the build status
[09:04:47.325] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:47.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:175:0) after 1m0s
[09:05:47.433] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:175:0)
[09:05:47.433] Timer tick, asking Buildbucket for the build status
[09:05:47.560] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:47.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:176:0) after 1m0s
[09:06:48.472] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:176:0)
[09:06:48.472] Timer tick, asking Buildbucket for the build status
[09:06:48.560] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:48.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:177:0) after 1m0s
[09:07:48.715] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:177:0)
[09:07:48.715] Timer tick, asking Buildbucket for the build status
[09:07:49.060] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:49.060] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:178:0) after 1m0s
[09:08:49.155] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:178:0)
[09:08:49.155] Timer tick, asking Buildbucket for the build status
[09:08:50.121] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:50.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:179:0) after 1m0s
[09:09:50.214] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:179:0)
[09:09:50.214] Timer tick, asking Buildbucket for the build status
[09:09:50.677] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:50.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:180:0) after 1m0s
[09:10:50.731] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:180:0)
[09:10:50.731] Timer tick, asking Buildbucket for the build status
[09:10:51.038] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:51.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:181:0) after 1m0s
[09:11:51.140] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:181:0)
[09:11:51.140] Timer tick, asking Buildbucket for the build status
[09:11:51.526] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:51.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:182:0) after 1m0s
[09:12:51.555] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:182:0)
[09:12:51.555] Timer tick, asking Buildbucket for the build status
[09:12:51.705] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:51.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:183:0) after 1m0s
[09:13:51.733] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:183:0)
[09:13:51.733] Timer tick, asking Buildbucket for the build status
[09:13:52.181] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:52.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:184:0) after 1m0s
[09:14:52.207] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:184:0)
[09:14:52.207] Timer tick, asking Buildbucket for the build status
[09:14:52.437] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:52.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:185:0) after 1m0s
[09:15:52.475] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:185:0)
[09:15:52.475] Timer tick, asking Buildbucket for the build status
[09:15:52.631] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:52.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:186:0) after 1m0s
[09:16:52.619] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:186:0)
[09:16:52.619] Timer tick, asking Buildbucket for the build status
[09:16:52.770] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:52.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:187:0) after 1m0s
[09:17:52.823] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:187:0)
[09:17:52.824] Timer tick, asking Buildbucket for the build status
[09:17:53.327] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:53.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:188:0) after 1m0s
[09:18:53.426] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:188:0)
[09:18:53.426] Timer tick, asking Buildbucket for the build status
[09:18:53.512] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:53.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:189:0) after 1m0s
[09:19:53.588] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:189:0)
[09:19:53.588] Timer tick, asking Buildbucket for the build status
[09:19:53.730] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:53.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:190:0) after 1m0s
[09:20:54.639] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:190:0)
[09:20:54.639] Timer tick, asking Buildbucket for the build status
[09:20:54.843] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:54.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:191:0) after 1m0s
[09:21:54.848] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:191:0)
[09:21:54.848] Timer tick, asking Buildbucket for the build status
[09:21:54.973] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:54.973] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:192:0) after 1m0s
[09:22:55.051] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:192:0)
[09:22:55.051] Timer tick, asking Buildbucket for the build status
[09:22:55.164] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:55.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:193:0) after 1m0s
[09:23:55.194] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:193:0)
[09:23:55.194] Timer tick, asking Buildbucket for the build status
[09:23:55.409] Build 8856974508359053008: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:55.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022404570416572720:194:0) after 1m0s
[09:24:48.533] Received PubSub notification, asking Buildbucket for the build status
[09:24:48.946] Build 8856974508359053008: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:24:48.946] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611739486533895",
"created_by": "project:chromium",
"created_ts": "1611727912521398",
"id": "8856974508359053008",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d33e6f5fdac171fca916c06a6cb6d3445e937f76\"}}",
"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\": \"d33e6f5fdac171fca916c06a6cb6d3445e937f76\", \"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-7-y9nh\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 1473, \"got_angle_revision\": \"f9afaaeedee68302e9936532de16126313a3bd02\", \"got_dawn_revision\": \"2df77f4325841e667e6b9ff9921fd626496a3b64\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"d33e6f5fdac171fca916c06a6cb6d3445e937f76\", \"got_revision_cp\": \"refs/heads/master@{#847512}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"aec0826aba0db51914209ec85a515d3a99311f07\", \"got_v8_revision\": \"5a6bbde4f5b86e56cbb0827de847a936161efbfe\", \"got_v8_revision_cp\": \"refs/heads/9.0.80@{#1}\", \"got_webrtc_revision\": \"fae4fb13451bea44ae1a94509befe8d7c6b11eb6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33077}\", \"mirrored_builders\": [\"tryserver.chromium:win-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d33e6f5fdac171fca916c06a6cb6d3445e937f76\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-7-y9nh\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5672-4713de1\"], \"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": "1611727960841881",
"status": "COMPLETED",
"status_changed_ts": "1611739487010869",
"tags": [
"build_address:luci.chromium.ci/win-official/1473",
"builder:win-official",
"buildset:commit/git/d33e6f5fdac171fca916c06a6cb6d3445e937f76",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d33e6f5fdac171fca916c06a6cb6d3445e937f76",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022404570416572720",
"scheduler_job_id:chromium/win-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8856974508359053008/+/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:515b49cc3b185b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1611739487028967",
"url": "https://ci.chromium.org/b/8856974508359053008",
"utcnow_ts": "1611739488895977"
}
}
[09:24:48.946] Invocation finished in 3h12m57.7711987s with status SUCCEEDED