[17:40:45.110] New invocation is queued and will start shortly
[17:40:46.609] Starting the invocation (attempt 1)
[17:40:46.675] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:40:46.675] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:40:46.675] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9037309728119774288",
"parameters_json": "{\"builder_name\":\"win32-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"aefc5f1eb5484a6b6b262c906e38cc31c4b183a5\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win32-official",
"scheduler_invocation_id:9037309728119774288",
"scheduler_job_id:chromium/win32-official",
"user_agent:luci-scheduler",
"buildset:commit/git/aefc5f1eb5484a6b6b262c906e38cc31c4b183a5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/aefc5f1eb5484a6b6b262c906e38cc31c4b183a5",
"gitiles_ref:refs/heads/master"
]
}
[17:40:47.344] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1597513246765917",
"id": "8871879665718844256",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"aefc5f1eb5484a6b6b262c906e38cc31c4b183a5\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1597513247208000",
"tags": [
"build_address:luci.chromium.ci/win32-official/318",
"builder:win32-official",
"buildset:commit/git/aefc5f1eb5484a6b6b262c906e38cc31c4b183a5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/aefc5f1eb5484a6b6b262c906e38cc31c4b183a5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037309728119774288",
"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/8871879665718844256/+/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": "1597513247208198",
"url": "https://ci.chromium.org/b/8871879665718844256",
"utcnow_ts": "1597513247335910"
}
}
[17:40:47.344] Task URL: https://ci.chromium.org/b/8871879665718844256
[17:40:47.344] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:2:0) after 1m0s
[17:41:05.785] Received PubSub notification, asking Buildbucket for the build status
[17:41:05.887] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:47.328] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:2:0)
[17:41:47.328] Timer tick, asking Buildbucket for the build status
[17:41:47.488] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:47.488] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:4:0) after 1m0s
[17:42:47.583] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:4:0)
[17:42:47.583] Timer tick, asking Buildbucket for the build status
[17:42:47.665] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:47.665] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:5:0) after 1m0s
[17:43:47.758] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:5:0)
[17:43:47.758] Timer tick, asking Buildbucket for the build status
[17:43:48.166] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:48.166] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:6:0) after 1m0s
[17:44:48.220] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:6:0)
[17:44:48.220] Timer tick, asking Buildbucket for the build status
[17:44:48.313] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:48.313] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:7:0) after 1m0s
[17:45:48.396] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:7:0)
[17:45:48.396] Timer tick, asking Buildbucket for the build status
[17:45:48.475] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:48.475] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:8:0) after 1m0s
[17:46:48.502] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:8:0)
[17:46:48.502] Timer tick, asking Buildbucket for the build status
[17:46:48.785] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:48.785] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:9:0) after 1m0s
[17:47:48.890] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:9:0)
[17:47:48.890] Timer tick, asking Buildbucket for the build status
[17:47:48.978] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:48.978] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:10:0) after 1m0s
[17:48:49.123] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:10:0)
[17:48:49.123] Timer tick, asking Buildbucket for the build status
[17:48:49.296] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:49.296] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:11:0) after 1m0s
[17:49:49.361] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:11:0)
[17:49:49.361] Timer tick, asking Buildbucket for the build status
[17:49:49.459] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:49.459] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:12:0) after 1m0s
[17:50:49.493] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:12:0)
[17:50:49.493] Timer tick, asking Buildbucket for the build status
[17:50:49.617] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:49.617] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:13:0) after 1m0s
[17:51:49.671] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:13:0)
[17:51:49.671] Timer tick, asking Buildbucket for the build status
[17:51:50.043] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:50.043] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:14:0) after 1m0s
[17:52:50.208] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:14:0)
[17:52:50.208] Timer tick, asking Buildbucket for the build status
[17:52:50.326] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:50.326] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:15:0) after 1m0s
[17:53:50.347] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:15:0)
[17:53:50.347] Timer tick, asking Buildbucket for the build status
[17:53:50.448] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:50.448] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:16:0) after 1m0s
[17:54:50.496] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:16:0)
[17:54:50.496] Timer tick, asking Buildbucket for the build status
[17:54:50.630] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:50.630] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:17:0) after 1m0s
[17:55:50.703] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:17:0)
[17:55:50.703] Timer tick, asking Buildbucket for the build status
[17:55:50.818] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:50.818] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:18:0) after 1m0s
[17:56:50.841] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:18:0)
[17:56:50.841] Timer tick, asking Buildbucket for the build status
[17:56:50.933] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:50.934] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:19:0) after 1m0s
[17:57:50.959] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:19:0)
[17:57:50.959] Timer tick, asking Buildbucket for the build status
[17:57:51.062] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:51.062] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:20:0) after 1m0s
[17:58:51.099] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:20:0)
[17:58:51.099] Timer tick, asking Buildbucket for the build status
[17:58:51.397] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:51.397] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:21:0) after 1m0s
[17:59:51.468] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:21:0)
[17:59:51.468] Timer tick, asking Buildbucket for the build status
[17:59:53.428] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:53.428] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:22:0) after 1m0s
[18:00:53.470] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:22:0)
[18:00:53.470] Timer tick, asking Buildbucket for the build status
[18:00:53.831] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:53.831] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:23:0) after 1m0s
[18:01:53.850] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:23:0)
[18:01:53.850] Timer tick, asking Buildbucket for the build status
[18:01:53.979] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:53.979] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:24:0) after 1m0s
[18:02:54.001] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:24:0)
[18:02:54.001] Timer tick, asking Buildbucket for the build status
[18:02:54.313] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:54.313] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:25:0) after 1m0s
[18:03:54.336] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:25:0)
[18:03:54.336] Timer tick, asking Buildbucket for the build status
[18:03:54.408] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:54.408] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:26:0) after 1m0s
[18:04:54.437] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:26:0)
[18:04:54.437] Timer tick, asking Buildbucket for the build status
[18:04:55.108] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:55.108] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:27:0) after 1m0s
[18:05:55.149] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:27:0)
[18:05:55.149] Timer tick, asking Buildbucket for the build status
[18:05:55.394] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:55.394] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:28:0) after 1m0s
[18:06:55.425] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:28:0)
[18:06:55.425] Timer tick, asking Buildbucket for the build status
[18:06:55.678] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:55.678] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:29:0) after 1m0s
[18:07:55.671] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:29:0)
[18:07:55.671] Timer tick, asking Buildbucket for the build status
[18:07:55.760] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:55.760] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:30:0) after 1m0s
[18:08:55.876] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:30:0)
[18:08:55.876] Timer tick, asking Buildbucket for the build status
[18:08:55.968] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:55.968] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:31:0) after 1m0s
[18:09:55.995] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:31:0)
[18:09:55.995] Timer tick, asking Buildbucket for the build status
[18:09:56.935] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:56.935] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:32:0) after 1m0s
[18:10:57.012] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:32:0)
[18:10:57.012] Timer tick, asking Buildbucket for the build status
[18:10:57.420] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:57.420] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:33:0) after 1m0s
[18:11:57.505] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:33:0)
[18:11:57.505] Timer tick, asking Buildbucket for the build status
[18:11:57.792] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:57.792] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:34:0) after 1m0s
[18:12:57.854] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:34:0)
[18:12:57.854] Timer tick, asking Buildbucket for the build status
[18:12:57.925] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:57.925] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:35:0) after 1m0s
[18:13:57.944] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:35:0)
[18:13:57.944] Timer tick, asking Buildbucket for the build status
[18:13:58.385] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:58.385] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:36:0) after 1m0s
[18:14:58.463] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:36:0)
[18:14:58.463] Timer tick, asking Buildbucket for the build status
[18:14:58.616] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:58.616] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:37:0) after 1m0s
[18:15:58.687] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:37:0)
[18:15:58.687] Timer tick, asking Buildbucket for the build status
[18:15:58.813] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:58.813] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:38:0) after 1m0s
[18:16:58.842] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:38:0)
[18:16:58.842] Timer tick, asking Buildbucket for the build status
[18:16:58.920] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:58.920] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:39:0) after 1m0s
[18:17:58.991] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:39:0)
[18:17:58.991] Timer tick, asking Buildbucket for the build status
[18:17:59.079] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:59.079] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:40:0) after 1m0s
[18:18:59.178] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:40:0)
[18:18:59.178] Timer tick, asking Buildbucket for the build status
[18:18:59.509] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:59.509] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:41:0) after 1m0s
[18:19:59.557] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:41:0)
[18:19:59.557] Timer tick, asking Buildbucket for the build status
[18:19:59.751] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:59.751] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:42:0) after 1m0s
[18:21:00.024] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:42:0)
[18:21:00.024] Timer tick, asking Buildbucket for the build status
[18:21:00.202] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:00.202] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:43:0) after 1m0s
[18:22:00.225] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:43:0)
[18:22:00.225] Timer tick, asking Buildbucket for the build status
[18:22:01.331] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:01.331] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:44:0) after 1m0s
[18:23:01.675] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:44:0)
[18:23:01.675] Timer tick, asking Buildbucket for the build status
[18:23:01.827] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:01.827] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:45:0) after 1m0s
[18:24:02.710] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:45:0)
[18:24:02.710] Timer tick, asking Buildbucket for the build status
[18:24:02.812] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:02.812] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:46:0) after 1m0s
[18:25:02.843] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:46:0)
[18:25:02.843] Timer tick, asking Buildbucket for the build status
[18:25:03.073] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:03.073] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:47:0) after 1m0s
[18:26:03.136] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:47:0)
[18:26:03.136] Timer tick, asking Buildbucket for the build status
[18:26:03.351] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:03.351] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:48:0) after 1m0s
[18:27:03.367] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:48:0)
[18:27:03.367] Timer tick, asking Buildbucket for the build status
[18:27:03.959] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:03.959] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:49:0) after 1m0s
[18:28:04.016] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:49:0)
[18:28:04.016] Timer tick, asking Buildbucket for the build status
[18:28:04.096] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:04.097] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:50:0) after 1m0s
[18:29:04.113] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:50:0)
[18:29:04.113] Timer tick, asking Buildbucket for the build status
[18:29:04.180] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:04.180] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:51:0) after 1m0s
[18:30:04.232] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:51:0)
[18:30:04.232] Timer tick, asking Buildbucket for the build status
[18:30:04.401] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:04.401] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:52:0) after 1m0s
[18:31:04.491] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:52:0)
[18:31:04.491] Timer tick, asking Buildbucket for the build status
[18:31:04.613] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:04.613] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:53:0) after 1m0s
[18:32:04.634] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:53:0)
[18:32:04.634] Timer tick, asking Buildbucket for the build status
[18:32:04.720] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:04.720] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:54:0) after 1m0s
[18:33:07.298] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:54:0)
[18:33:07.298] Timer tick, asking Buildbucket for the build status
[18:33:07.368] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:07.368] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:55:0) after 1m0s
[18:34:07.388] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:55:0)
[18:34:07.388] Timer tick, asking Buildbucket for the build status
[18:34:08.407] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:08.407] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:56:0) after 1m0s
[18:35:08.446] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:56:0)
[18:35:08.446] Timer tick, asking Buildbucket for the build status
[18:35:08.824] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:08.824] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:57:0) after 1m0s
[18:36:08.910] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:57:0)
[18:36:08.910] Timer tick, asking Buildbucket for the build status
[18:36:09.536] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:09.536] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:58:0) after 1m0s
[18:37:09.631] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:58:0)
[18:37:09.631] Timer tick, asking Buildbucket for the build status
[18:37:09.706] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:09.706] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:59:0) after 1m0s
[18:38:09.802] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:59:0)
[18:38:09.802] Timer tick, asking Buildbucket for the build status
[18:38:09.981] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:09.982] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:60:0) after 1m0s
[18:39:10.008] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:60:0)
[18:39:10.008] Timer tick, asking Buildbucket for the build status
[18:39:10.094] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:10.094] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:61:0) after 1m0s
[18:40:10.193] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:61:0)
[18:40:10.193] Timer tick, asking Buildbucket for the build status
[18:40:10.329] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:10.329] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:62:0) after 1m0s
[18:41:10.412] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:62:0)
[18:41:10.412] Timer tick, asking Buildbucket for the build status
[18:41:10.583] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:10.583] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:63:0) after 1m0s
[18:42:10.599] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:63:0)
[18:42:10.599] Timer tick, asking Buildbucket for the build status
[18:42:10.682] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:10.682] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:64:0) after 1m0s
[18:43:11.291] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:64:0)
[18:43:11.291] Timer tick, asking Buildbucket for the build status
[18:43:11.488] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:11.488] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:65:0) after 1m0s
[18:44:11.608] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:65:0)
[18:44:11.608] Timer tick, asking Buildbucket for the build status
[18:44:11.727] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:11.727] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:66:0) after 1m0s
[18:45:11.755] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:66:0)
[18:45:11.755] Timer tick, asking Buildbucket for the build status
[18:45:11.835] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:11.835] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:67:0) after 1m0s
[18:46:11.823] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:67:0)
[18:46:11.823] Timer tick, asking Buildbucket for the build status
[18:46:11.942] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:11.942] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:68:0) after 1m0s
[18:47:11.948] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:68:0)
[18:47:11.948] Timer tick, asking Buildbucket for the build status
[18:47:12.025] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:12.025] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:69:0) after 1m0s
[18:48:12.089] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:69:0)
[18:48:12.089] Timer tick, asking Buildbucket for the build status
[18:48:12.159] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:12.159] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:70:0) after 1m0s
[18:49:12.433] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:70:0)
[18:49:12.433] Timer tick, asking Buildbucket for the build status
[18:49:12.550] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:12.550] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:71:0) after 1m0s
[18:50:12.640] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:71:0)
[18:50:12.640] Timer tick, asking Buildbucket for the build status
[18:50:12.926] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:12.926] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:72:0) after 1m0s
[18:51:12.991] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:72:0)
[18:51:12.991] Timer tick, asking Buildbucket for the build status
[18:51:13.294] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:13.294] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:73:0) after 1m0s
[18:52:13.470] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:73:0)
[18:52:13.470] Timer tick, asking Buildbucket for the build status
[18:52:13.729] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:13.729] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:74:0) after 1m0s
[18:53:13.749] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:74:0)
[18:53:13.749] Timer tick, asking Buildbucket for the build status
[18:53:13.870] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:13.870] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:75:0) after 1m0s
[18:54:13.931] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:75:0)
[18:54:13.932] Timer tick, asking Buildbucket for the build status
[18:54:14.008] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:14.008] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:76:0) after 1m0s
[18:55:14.105] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:76:0)
[18:55:14.106] Timer tick, asking Buildbucket for the build status
[18:55:14.182] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:14.182] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:77:0) after 1m0s
[18:56:14.241] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:77:0)
[18:56:14.241] Timer tick, asking Buildbucket for the build status
[18:56:14.379] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:14.379] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:78:0) after 1m0s
[18:57:14.397] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:78:0)
[18:57:14.397] Timer tick, asking Buildbucket for the build status
[18:57:14.913] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:14.913] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:79:0) after 1m0s
[18:58:15.148] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:79:0)
[18:58:15.148] Timer tick, asking Buildbucket for the build status
[18:58:15.313] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:15.313] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:80:0) after 1m0s
[18:59:15.380] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:80:0)
[18:59:15.380] Timer tick, asking Buildbucket for the build status
[18:59:15.683] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:15.683] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:81:0) after 1m0s
[19:00:15.773] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:81:0)
[19:00:15.773] Timer tick, asking Buildbucket for the build status
[19:00:15.915] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:15.915] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:82:0) after 1m0s
[19:01:16.027] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:82:0)
[19:01:16.027] Timer tick, asking Buildbucket for the build status
[19:01:16.197] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:16.197] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:83:0) after 1m0s
[19:02:16.297] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:83:0)
[19:02:16.297] Timer tick, asking Buildbucket for the build status
[19:02:16.398] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:16.398] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:84:0) after 1m0s
[19:03:16.510] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:84:0)
[19:03:16.510] Timer tick, asking Buildbucket for the build status
[19:03:16.758] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:16.758] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:85:0) after 1m0s
[19:04:17.703] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:85:0)
[19:04:17.703] Timer tick, asking Buildbucket for the build status
[19:04:17.994] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:17.994] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:86:0) after 1m0s
[19:05:18.819] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:86:0)
[19:05:18.819] Timer tick, asking Buildbucket for the build status
[19:05:19.159] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:19.159] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:87:0) after 1m0s
[19:06:19.285] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:87:0)
[19:06:19.285] Timer tick, asking Buildbucket for the build status
[19:06:19.423] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:19.423] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:88:0) after 1m0s
[19:07:20.447] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:88:0)
[19:07:20.447] Timer tick, asking Buildbucket for the build status
[19:07:20.515] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:20.515] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:89:0) after 1m0s
[19:08:20.549] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:89:0)
[19:08:20.549] Timer tick, asking Buildbucket for the build status
[19:08:20.641] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:20.641] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:90:0) after 1m0s
[19:09:20.700] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:90:0)
[19:09:20.700] Timer tick, asking Buildbucket for the build status
[19:09:20.773] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:20.773] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:91:0) after 1m0s
[19:10:20.843] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:91:0)
[19:10:20.843] Timer tick, asking Buildbucket for the build status
[19:10:20.988] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:20.988] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:92:0) after 1m0s
[19:11:21.053] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:92:0)
[19:11:21.053] Timer tick, asking Buildbucket for the build status
[19:11:21.317] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:21.317] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:93:0) after 1m0s
[19:12:21.314] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:93:0)
[19:12:21.314] Timer tick, asking Buildbucket for the build status
[19:12:21.378] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:21.378] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:94:0) after 1m0s
[19:13:21.405] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:94:0)
[19:13:21.405] Timer tick, asking Buildbucket for the build status
[19:13:21.492] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:21.492] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:95:0) after 1m0s
[19:14:21.518] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:95:0)
[19:14:21.518] Timer tick, asking Buildbucket for the build status
[19:14:21.579] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:21.580] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:96:0) after 1m0s
[19:15:21.605] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:96:0)
[19:15:21.605] Timer tick, asking Buildbucket for the build status
[19:15:21.666] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:21.666] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:97:0) after 1m0s
[19:16:21.654] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:97:0)
[19:16:21.654] Timer tick, asking Buildbucket for the build status
[19:16:21.802] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:21.802] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:98:0) after 1m0s
[19:17:21.826] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:98:0)
[19:17:21.826] Timer tick, asking Buildbucket for the build status
[19:17:21.894] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:21.894] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:99:0) after 1m0s
[19:18:21.962] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:99:0)
[19:18:21.962] Timer tick, asking Buildbucket for the build status
[19:18:22.040] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:22.040] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:100:0) after 1m0s
[19:19:22.095] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:100:0)
[19:19:22.095] Timer tick, asking Buildbucket for the build status
[19:19:22.169] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:22.169] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:101:0) after 1m0s
[19:20:22.521] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:101:0)
[19:20:22.521] Timer tick, asking Buildbucket for the build status
[19:20:22.820] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:22.820] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:102:0) after 1m0s
[19:21:22.931] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:102:0)
[19:21:22.932] Timer tick, asking Buildbucket for the build status
[19:21:23.875] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:23.875] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:103:0) after 1m0s
[19:22:23.906] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:103:0)
[19:22:23.906] Timer tick, asking Buildbucket for the build status
[19:22:24.214] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:24.214] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:104:0) after 1m0s
[19:23:24.349] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:104:0)
[19:23:24.349] Timer tick, asking Buildbucket for the build status
[19:23:24.744] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:24.744] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:105:0) after 1m0s
[19:24:24.862] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:105:0)
[19:24:24.862] Timer tick, asking Buildbucket for the build status
[19:24:24.933] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:24.933] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:106:0) after 1m0s
[19:25:24.984] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:106:0)
[19:25:24.984] Timer tick, asking Buildbucket for the build status
[19:25:25.338] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:25.338] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:107:0) after 1m0s
[19:26:25.435] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:107:0)
[19:26:25.435] Timer tick, asking Buildbucket for the build status
[19:26:25.902] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:25.902] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:108:0) after 1m0s
[19:27:25.933] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:108:0)
[19:27:25.933] Timer tick, asking Buildbucket for the build status
[19:27:26.405] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:26.405] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:109:0) after 1m0s
[19:28:26.496] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:109:0)
[19:28:26.496] Timer tick, asking Buildbucket for the build status
[19:28:26.562] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:26.562] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:110:0) after 1m0s
[19:29:26.672] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:110:0)
[19:29:26.672] Timer tick, asking Buildbucket for the build status
[19:29:26.732] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:26.732] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:111:0) after 1m0s
[19:30:26.785] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:111:0)
[19:30:26.785] Timer tick, asking Buildbucket for the build status
[19:30:26.850] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:26.850] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:112:0) after 1m0s
[19:31:26.876] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:112:0)
[19:31:26.876] Timer tick, asking Buildbucket for the build status
[19:31:27.070] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:27.070] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:113:0) after 1m0s
[19:32:27.148] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:113:0)
[19:32:27.148] Timer tick, asking Buildbucket for the build status
[19:32:27.322] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:27.322] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:114:0) after 1m0s
[19:33:27.402] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:114:0)
[19:33:27.402] Timer tick, asking Buildbucket for the build status
[19:33:27.643] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:27.643] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:115:0) after 1m0s
[19:34:27.670] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:115:0)
[19:34:27.670] Timer tick, asking Buildbucket for the build status
[19:34:27.805] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:27.805] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:116:0) after 1m0s
[19:35:27.834] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:116:0)
[19:35:27.834] Timer tick, asking Buildbucket for the build status
[19:35:27.892] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:27.892] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:117:0) after 1m0s
[19:36:27.925] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:117:0)
[19:36:27.925] Timer tick, asking Buildbucket for the build status
[19:36:28.031] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:28.031] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:118:0) after 1m0s
[19:37:28.106] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:118:0)
[19:37:28.106] Timer tick, asking Buildbucket for the build status
[19:37:28.195] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:28.195] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:119:0) after 1m0s
[19:38:28.263] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:119:0)
[19:38:28.263] Timer tick, asking Buildbucket for the build status
[19:38:28.355] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:28.355] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:120:0) after 1m0s
[19:39:28.397] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:120:0)
[19:39:28.397] Timer tick, asking Buildbucket for the build status
[19:39:28.545] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:28.545] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:121:0) after 1m0s
[19:40:28.578] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:121:0)
[19:40:28.578] Timer tick, asking Buildbucket for the build status
[19:40:28.707] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:28.707] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:122:0) after 1m0s
[19:41:28.738] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:122:0)
[19:41:28.738] Timer tick, asking Buildbucket for the build status
[19:41:28.869] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:28.869] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:123:0) after 1m0s
[19:42:29.051] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:123:0)
[19:42:29.051] Timer tick, asking Buildbucket for the build status
[19:42:29.257] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:29.258] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:124:0) after 1m0s
[19:43:29.264] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:124:0)
[19:43:29.264] Timer tick, asking Buildbucket for the build status
[19:43:29.610] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:29.610] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:125:0) after 1m0s
[19:44:29.740] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:125:0)
[19:44:29.740] Timer tick, asking Buildbucket for the build status
[19:44:29.843] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:29.843] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:126:0) after 1m0s
[19:45:29.870] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:126:0)
[19:45:29.870] Timer tick, asking Buildbucket for the build status
[19:45:29.936] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:29.936] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:127:0) after 1m0s
[19:46:29.975] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:127:0)
[19:46:29.975] Timer tick, asking Buildbucket for the build status
[19:46:30.087] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:30.087] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:128:0) after 1m0s
[19:47:30.102] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:128:0)
[19:47:30.102] Timer tick, asking Buildbucket for the build status
[19:47:30.175] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:30.175] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:129:0) after 1m0s
[19:48:30.213] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:129:0)
[19:48:30.213] Timer tick, asking Buildbucket for the build status
[19:48:30.512] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:30.512] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:130:0) after 1m0s
[19:49:30.541] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:130:0)
[19:49:30.541] Timer tick, asking Buildbucket for the build status
[19:49:30.702] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:30.702] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:131:0) after 1m0s
[19:50:30.735] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:131:0)
[19:50:30.735] Timer tick, asking Buildbucket for the build status
[19:50:30.845] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:30.845] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:132:0) after 1m0s
[19:51:30.873] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:132:0)
[19:51:30.873] Timer tick, asking Buildbucket for the build status
[19:51:30.986] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:30.986] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:133:0) after 1m0s
[19:52:31.028] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:133:0)
[19:52:31.028] Timer tick, asking Buildbucket for the build status
[19:52:31.284] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:31.284] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:134:0) after 1m0s
[19:53:31.329] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:134:0)
[19:53:31.329] Timer tick, asking Buildbucket for the build status
[19:53:31.409] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:31.410] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:135:0) after 1m0s
[19:54:31.467] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:135:0)
[19:54:31.467] Timer tick, asking Buildbucket for the build status
[19:54:31.559] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:31.559] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:136:0) after 1m0s
[19:55:32.095] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:136:0)
[19:55:32.095] Timer tick, asking Buildbucket for the build status
[19:55:32.151] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:32.151] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:137:0) after 1m0s
[19:56:32.187] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:137:0)
[19:56:32.187] Timer tick, asking Buildbucket for the build status
[19:56:32.342] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:32.342] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:138:0) after 1m0s
[19:57:32.419] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:138:0)
[19:57:32.419] Timer tick, asking Buildbucket for the build status
[19:57:32.606] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:32.606] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:139:0) after 1m0s
[19:58:32.647] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:139:0)
[19:58:32.647] Timer tick, asking Buildbucket for the build status
[19:58:32.821] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:32.821] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:140:0) after 1m0s
[19:59:32.846] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:140:0)
[19:59:32.846] Timer tick, asking Buildbucket for the build status
[19:59:32.918] Build 8871879665718844256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:32.918] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9037309728119774288:141:0) after 1m0s
[20:00:29.196] Received PubSub notification, asking Buildbucket for the build status
[20:00:29.264] Build 8871879665718844256: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:00:29.264] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1597521627160223",
"created_by": "project:chromium",
"created_ts": "1597513246765917",
"id": "8871879665718844256",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"aefc5f1eb5484a6b6b262c906e38cc31c4b183a5\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/chromium_tests\": {\"bucketed_triggers\": true}, \"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$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-vpul\", \"branch\": \"refs/heads/master\", \"buildername\": \"win32-official\", \"buildnumber\": 318, \"got_angle_revision\": \"9ae6d7f2ee9e12b707483dc9cadd7c3529595016\", \"got_dawn_revision\": \"5e9b29fab92d6d79125555ea0d84afde9ace908b\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"aefc5f1eb5484a6b6b262c906e38cc31c4b183a5\", \"got_revision_cp\": \"refs/heads/master@{#798451}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"abe07b9438553f741e776dedb59a2d4b502a9983\", \"got_v8_revision\": \"26d2954fbe8d1d68f3daf3fc59f1826dd91d6383\", \"got_v8_revision_cp\": \"refs/heads/8.6.384@{#1}\", \"got_webrtc_revision\": \"383f2cfca4a1ebd9626134101fe70d5a716b2d92\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31921}\", \"mastername\": \"chromium\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"aefc5f1eb5484a6b6b262c906e38cc31c4b183a5\"}, \"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-7-vpul\"], \"image\": [\"chrome-win10-1703-20073100-2eb11ddbc6e\"], \"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\": [\"5488-fc78d20\"], \"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": "1597513265178471",
"status": "COMPLETED",
"status_changed_ts": "1597521627986587",
"tags": [
"build_address:luci.chromium.ci/win32-official/318",
"builder:win32-official",
"buildset:commit/git/aefc5f1eb5484a6b6b262c906e38cc31c4b183a5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/aefc5f1eb5484a6b6b262c906e38cc31c4b183a5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037309728119774288",
"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/8871879665718844256/+/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:4e0c0733ec193210",
"user_agent:luci-scheduler"
],
"updated_ts": "1597521628202934",
"url": "https://ci.chromium.org/b/8871879665718844256",
"utcnow_ts": "1597521629257084"
}
}
[20:00:29.264] Invocation finished in 2h19m44.172554453s with status SUCCEEDED