[20:01:38.248] New invocation is queued and will start shortly
[20:01:39.401] Starting the invocation (attempt 1)
[20:01:39.436] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:01:39.437] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:01:39.437] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9025342064797216208",
"parameters_json": "{\"builder_name\":\"win32-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"639424aef7a8638d42da8e23a2252ba44b6c6ae1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win32-official",
"scheduler_invocation_id:9025342064797216208",
"scheduler_job_id:chromium/win32-official",
"user_agent:luci-scheduler",
"buildset:commit/git/639424aef7a8638d42da8e23a2252ba44b6c6ae1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/639424aef7a8638d42da8e23a2252ba44b6c6ae1",
"gitiles_ref:refs/heads/master"
]
}
[20:01:39.924] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608926499520978",
"id": "8859912002797905632",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"639424aef7a8638d42da8e23a2252ba44b6c6ae1\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608926499825240",
"tags": [
"build_address:luci.chromium.ci/win32-official/1194",
"builder:win32-official",
"buildset:commit/git/639424aef7a8638d42da8e23a2252ba44b6c6ae1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/639424aef7a8638d42da8e23a2252ba44b6c6ae1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025342064797216208",
"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/8859912002797905632/+/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": "1608926499825342",
"url": "https://ci.chromium.org/b/8859912002797905632",
"utcnow_ts": "1608926499917029"
}
}
[20:01:39.924] Task URL: https://ci.chromium.org/b/8859912002797905632
[20:01:39.924] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:2:0) after 1m0s
[20:01:55.587] Received PubSub notification, asking Buildbucket for the build status
[20:01:55.695] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:39.999] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:2:0)
[20:02:39.999] Timer tick, asking Buildbucket for the build status
[20:02:40.086] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:40.086] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:4:0) after 1m0s
[20:03:40.156] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:4:0)
[20:03:40.156] Timer tick, asking Buildbucket for the build status
[20:03:40.249] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:40.249] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:5:0) after 1m0s
[20:04:40.266] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:5:0)
[20:04:40.266] Timer tick, asking Buildbucket for the build status
[20:04:40.801] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:40.801] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:6:0) after 1m0s
[20:05:40.991] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:6:0)
[20:05:40.991] Timer tick, asking Buildbucket for the build status
[20:05:41.053] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:41.053] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:7:0) after 1m0s
[20:06:41.075] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:7:0)
[20:06:41.075] Timer tick, asking Buildbucket for the build status
[20:06:41.579] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:41.579] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:8:0) after 1m0s
[20:07:41.587] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:8:0)
[20:07:41.587] Timer tick, asking Buildbucket for the build status
[20:07:41.787] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:41.787] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:9:0) after 1m0s
[20:08:41.807] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:9:0)
[20:08:41.807] Timer tick, asking Buildbucket for the build status
[20:08:41.872] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:41.872] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:10:0) after 1m0s
[20:09:41.911] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:10:0)
[20:09:41.911] Timer tick, asking Buildbucket for the build status
[20:09:42.117] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:42.117] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:11:0) after 1m0s
[20:10:42.156] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:11:0)
[20:10:42.156] Timer tick, asking Buildbucket for the build status
[20:10:42.229] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:42.229] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:12:0) after 1m0s
[20:11:42.323] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:12:0)
[20:11:42.323] Timer tick, asking Buildbucket for the build status
[20:11:42.421] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:42.421] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:13:0) after 1m0s
[20:12:42.529] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:13:0)
[20:12:42.529] Timer tick, asking Buildbucket for the build status
[20:12:42.585] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:42.585] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:14:0) after 1m0s
[20:13:42.605] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:14:0)
[20:13:42.605] Timer tick, asking Buildbucket for the build status
[20:13:42.801] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:42.801] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:15:0) after 1m0s
[20:14:42.826] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:15:0)
[20:14:42.826] Timer tick, asking Buildbucket for the build status
[20:14:42.923] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:42.923] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:16:0) after 1m0s
[20:15:42.952] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:16:0)
[20:15:42.952] Timer tick, asking Buildbucket for the build status
[20:15:43.011] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:43.011] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:17:0) after 1m0s
[20:16:43.036] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:17:0)
[20:16:43.036] Timer tick, asking Buildbucket for the build status
[20:16:43.137] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:43.137] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:18:0) after 1m0s
[20:17:43.187] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:18:0)
[20:17:43.187] Timer tick, asking Buildbucket for the build status
[20:17:43.569] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:43.569] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:19:0) after 1m0s
[20:18:43.745] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:19:0)
[20:18:43.745] Timer tick, asking Buildbucket for the build status
[20:18:43.859] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:43.859] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:20:0) after 1m0s
[20:19:43.927] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:20:0)
[20:19:43.927] Timer tick, asking Buildbucket for the build status
[20:19:44.016] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:44.016] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:21:0) after 1m0s
[20:20:44.131] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:21:0)
[20:20:44.131] Timer tick, asking Buildbucket for the build status
[20:20:44.186] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:44.186] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:22:0) after 1m0s
[20:21:44.215] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:22:0)
[20:21:44.215] Timer tick, asking Buildbucket for the build status
[20:21:44.313] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:44.313] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:23:0) after 1m0s
[20:22:44.429] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:23:0)
[20:22:44.429] Timer tick, asking Buildbucket for the build status
[20:22:44.672] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:44.672] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:24:0) after 1m0s
[20:23:44.698] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:24:0)
[20:23:44.698] Timer tick, asking Buildbucket for the build status
[20:23:44.762] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:44.762] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:25:0) after 1m0s
[20:24:44.842] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:25:0)
[20:24:44.842] Timer tick, asking Buildbucket for the build status
[20:24:45.012] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:45.012] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:26:0) after 1m0s
[20:25:45.067] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:26:0)
[20:25:45.067] Timer tick, asking Buildbucket for the build status
[20:25:45.476] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:45.476] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:27:0) after 1m0s
[20:26:45.559] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:27:0)
[20:26:45.559] Timer tick, asking Buildbucket for the build status
[20:26:45.697] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:45.697] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:28:0) after 1m0s
[20:27:45.736] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:28:0)
[20:27:45.736] Timer tick, asking Buildbucket for the build status
[20:27:45.825] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:45.825] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:29:0) after 1m0s
[20:28:45.881] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:29:0)
[20:28:45.881] Timer tick, asking Buildbucket for the build status
[20:28:46.028] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:46.028] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:30:0) after 1m0s
[20:29:46.045] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:30:0)
[20:29:46.045] Timer tick, asking Buildbucket for the build status
[20:29:46.327] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:46.327] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:31:0) after 1m0s
[20:30:46.407] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:31:0)
[20:30:46.407] Timer tick, asking Buildbucket for the build status
[20:30:46.466] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:46.466] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:32:0) after 1m0s
[20:31:46.503] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:32:0)
[20:31:46.503] Timer tick, asking Buildbucket for the build status
[20:31:46.602] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:46.602] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:33:0) after 1m0s
[20:32:46.715] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:33:0)
[20:32:46.715] Timer tick, asking Buildbucket for the build status
[20:32:46.884] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:46.884] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:34:0) after 1m0s
[20:33:47.008] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:34:0)
[20:33:47.008] Timer tick, asking Buildbucket for the build status
[20:33:47.306] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:47.306] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:35:0) after 1m0s
[20:34:47.425] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:35:0)
[20:34:47.425] Timer tick, asking Buildbucket for the build status
[20:34:47.666] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:47.666] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:36:0) after 1m0s
[20:35:47.696] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:36:0)
[20:35:47.696] Timer tick, asking Buildbucket for the build status
[20:35:47.805] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:47.805] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:37:0) after 1m0s
[20:36:47.865] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:37:0)
[20:36:47.865] Timer tick, asking Buildbucket for the build status
[20:36:48.088] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:48.088] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:38:0) after 1m0s
[20:37:48.107] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:38:0)
[20:37:48.107] Timer tick, asking Buildbucket for the build status
[20:37:48.507] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:48.507] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:39:0) after 1m0s
[20:38:48.524] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:39:0)
[20:38:48.524] Timer tick, asking Buildbucket for the build status
[20:38:48.590] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:48.590] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:40:0) after 1m0s
[20:39:48.609] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:40:0)
[20:39:48.609] Timer tick, asking Buildbucket for the build status
[20:39:48.715] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:48.715] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:41:0) after 1m0s
[20:40:49.007] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:41:0)
[20:40:49.007] Timer tick, asking Buildbucket for the build status
[20:40:49.070] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:49.070] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:42:0) after 1m0s
[20:41:49.142] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:42:0)
[20:41:49.142] Timer tick, asking Buildbucket for the build status
[20:41:49.246] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:49.246] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:43:0) after 1m0s
[20:42:49.317] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:43:0)
[20:42:49.317] Timer tick, asking Buildbucket for the build status
[20:42:49.519] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:49.519] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:44:0) after 1m0s
[20:43:49.551] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:44:0)
[20:43:49.551] Timer tick, asking Buildbucket for the build status
[20:43:49.605] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:49.605] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:45:0) after 1m0s
[20:44:49.688] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:45:0)
[20:44:49.688] Timer tick, asking Buildbucket for the build status
[20:44:49.744] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:49.744] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:46:0) after 1m0s
[20:45:49.784] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:46:0)
[20:45:49.784] Timer tick, asking Buildbucket for the build status
[20:45:50.030] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:50.030] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:47:0) after 1m0s
[20:46:50.051] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:47:0)
[20:46:50.051] Timer tick, asking Buildbucket for the build status
[20:46:54.898] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:54.898] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:48:0) after 1m0s
[20:47:54.981] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:48:0)
[20:47:54.981] Timer tick, asking Buildbucket for the build status
[20:47:55.043] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:55.043] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:49:0) after 1m0s
[20:48:55.089] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:49:0)
[20:48:55.089] Timer tick, asking Buildbucket for the build status
[20:48:55.155] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:55.155] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:50:0) after 1m0s
[20:49:55.179] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:50:0)
[20:49:55.179] Timer tick, asking Buildbucket for the build status
[20:49:55.320] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:55.320] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:51:0) after 1m0s
[20:50:55.328] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:51:0)
[20:50:55.328] Timer tick, asking Buildbucket for the build status
[20:50:55.398] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:55.398] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:52:0) after 1m0s
[20:51:55.374] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:52:0)
[20:51:55.374] Timer tick, asking Buildbucket for the build status
[20:51:55.702] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:55.702] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:53:0) after 1m0s
[20:52:55.787] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:53:0)
[20:52:55.787] Timer tick, asking Buildbucket for the build status
[20:52:55.846] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:55.846] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:54:0) after 1m0s
[20:53:55.836] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:54:0)
[20:53:55.836] Timer tick, asking Buildbucket for the build status
[20:53:56.005] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:56.005] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:55:0) after 1m0s
[20:54:56.008] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:55:0)
[20:54:56.008] Timer tick, asking Buildbucket for the build status
[20:54:56.285] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:56.285] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:56:0) after 1m0s
[20:55:56.454] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:56:0)
[20:55:56.454] Timer tick, asking Buildbucket for the build status
[20:55:56.526] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:56.526] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:57:0) after 1m0s
[20:56:56.546] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:57:0)
[20:56:56.546] Timer tick, asking Buildbucket for the build status
[20:56:56.625] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:56.625] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:58:0) after 1m0s
[20:57:56.658] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:58:0)
[20:57:56.658] Timer tick, asking Buildbucket for the build status
[20:57:56.821] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:56.821] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:59:0) after 1m0s
[20:58:56.899] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:59:0)
[20:58:56.899] Timer tick, asking Buildbucket for the build status
[20:58:56.963] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:56.963] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:60:0) after 1m0s
[20:59:57.029] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:60:0)
[20:59:57.029] Timer tick, asking Buildbucket for the build status
[20:59:57.119] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:57.119] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:61:0) after 1m0s
[21:00:57.139] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:61:0)
[21:00:57.139] Timer tick, asking Buildbucket for the build status
[21:00:57.245] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:57.245] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:62:0) after 1m0s
[21:01:57.623] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:62:0)
[21:01:57.623] Timer tick, asking Buildbucket for the build status
[21:01:57.811] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:57.811] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:63:0) after 1m0s
[21:02:57.807] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:63:0)
[21:02:57.807] Timer tick, asking Buildbucket for the build status
[21:02:57.973] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:57.973] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:64:0) after 1m0s
[21:03:57.951] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:64:0)
[21:03:57.951] Timer tick, asking Buildbucket for the build status
[21:03:58.012] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:58.012] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:65:0) after 1m0s
[21:04:58.036] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:65:0)
[21:04:58.036] Timer tick, asking Buildbucket for the build status
[21:04:58.136] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:58.136] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:66:0) after 1m0s
[21:05:58.159] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:66:0)
[21:05:58.159] Timer tick, asking Buildbucket for the build status
[21:05:58.211] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:58.211] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:67:0) after 1m0s
[21:06:58.232] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:67:0)
[21:06:58.232] Timer tick, asking Buildbucket for the build status
[21:06:58.323] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:58.323] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:68:0) after 1m0s
[21:07:58.348] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:68:0)
[21:07:58.348] Timer tick, asking Buildbucket for the build status
[21:07:58.399] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:58.399] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:69:0) after 1m0s
[21:08:58.440] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:69:0)
[21:08:58.440] Timer tick, asking Buildbucket for the build status
[21:08:58.524] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:58.524] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:70:0) after 1m0s
[21:09:58.548] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:70:0)
[21:09:58.548] Timer tick, asking Buildbucket for the build status
[21:09:58.706] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:58.706] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:71:0) after 1m0s
[21:11:00.216] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:71:0)
[21:11:00.216] Timer tick, asking Buildbucket for the build status
[21:11:00.289] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:00.289] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:72:0) after 1m0s
[21:12:00.522] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:72:0)
[21:12:00.522] Timer tick, asking Buildbucket for the build status
[21:12:00.611] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:00.611] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:73:0) after 1m0s
[21:13:01.255] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:73:0)
[21:13:01.255] Timer tick, asking Buildbucket for the build status
[21:13:01.396] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:01.396] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:74:0) after 1m0s
[21:14:01.570] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:74:0)
[21:14:01.570] Timer tick, asking Buildbucket for the build status
[21:14:01.671] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:01.671] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:75:0) after 1m0s
[21:15:01.700] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:75:0)
[21:15:01.700] Timer tick, asking Buildbucket for the build status
[21:15:01.772] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:01.772] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:76:0) after 1m0s
[21:16:02.272] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:76:0)
[21:16:02.272] Timer tick, asking Buildbucket for the build status
[21:16:02.425] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:02.425] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:77:0) after 1m0s
[21:17:02.508] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:77:0)
[21:17:02.508] Timer tick, asking Buildbucket for the build status
[21:17:02.641] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:02.641] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:78:0) after 1m0s
[21:18:02.761] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:78:0)
[21:18:02.761] Timer tick, asking Buildbucket for the build status
[21:18:02.831] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:02.831] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:79:0) after 1m0s
[21:19:02.899] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:79:0)
[21:19:02.899] Timer tick, asking Buildbucket for the build status
[21:19:03.759] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:03.760] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:80:0) after 1m0s
[21:20:04.522] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:80:0)
[21:20:04.522] Timer tick, asking Buildbucket for the build status
[21:20:04.604] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:04.604] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:81:0) after 1m0s
[21:21:04.733] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:81:0)
[21:21:04.733] Timer tick, asking Buildbucket for the build status
[21:21:04.844] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:04.844] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:82:0) after 1m0s
[21:22:04.925] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:82:0)
[21:22:04.925] Timer tick, asking Buildbucket for the build status
[21:22:05.034] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:05.034] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:83:0) after 1m0s
[21:23:05.064] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:83:0)
[21:23:05.064] Timer tick, asking Buildbucket for the build status
[21:23:05.132] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:05.132] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:84:0) after 1m0s
[21:24:05.151] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:84:0)
[21:24:05.151] Timer tick, asking Buildbucket for the build status
[21:24:05.205] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:05.205] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:85:0) after 1m0s
[21:25:05.768] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:85:0)
[21:25:05.768] Timer tick, asking Buildbucket for the build status
[21:25:05.831] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:05.832] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:86:0) after 1m0s
[21:26:05.901] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:86:0)
[21:26:05.901] Timer tick, asking Buildbucket for the build status
[21:26:05.955] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:05.955] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:87:0) after 1m0s
[21:27:05.986] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:87:0)
[21:27:05.986] Timer tick, asking Buildbucket for the build status
[21:27:06.102] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:06.102] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:88:0) after 1m0s
[21:28:06.131] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:88:0)
[21:28:06.131] Timer tick, asking Buildbucket for the build status
[21:28:06.192] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:06.192] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:89:0) after 1m0s
[21:29:06.251] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:89:0)
[21:29:06.251] Timer tick, asking Buildbucket for the build status
[21:29:06.354] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:06.354] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:90:0) after 1m0s
[21:30:06.451] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:90:0)
[21:30:06.451] Timer tick, asking Buildbucket for the build status
[21:30:06.517] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:06.517] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:91:0) after 1m0s
[21:31:06.807] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:91:0)
[21:31:06.807] Timer tick, asking Buildbucket for the build status
[21:31:06.868] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:06.868] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:92:0) after 1m0s
[21:32:06.970] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:92:0)
[21:32:06.970] Timer tick, asking Buildbucket for the build status
[21:32:07.097] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:07.097] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:93:0) after 1m0s
[21:33:07.130] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:93:0)
[21:33:07.130] Timer tick, asking Buildbucket for the build status
[21:33:07.176] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:07.176] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:94:0) after 1m0s
[21:34:07.224] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:94:0)
[21:34:07.224] Timer tick, asking Buildbucket for the build status
[21:34:07.747] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:07.747] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:95:0) after 1m0s
[21:35:07.831] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:95:0)
[21:35:07.831] Timer tick, asking Buildbucket for the build status
[21:35:07.898] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:07.898] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:96:0) after 1m0s
[21:36:07.895] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:96:0)
[21:36:07.895] Timer tick, asking Buildbucket for the build status
[21:36:07.950] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:07.950] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:97:0) after 1m0s
[21:37:07.971] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:97:0)
[21:37:07.971] Timer tick, asking Buildbucket for the build status
[21:37:08.055] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:08.055] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:98:0) after 1m0s
[21:38:08.084] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:98:0)
[21:38:08.084] Timer tick, asking Buildbucket for the build status
[21:38:08.192] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:08.192] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:99:0) after 1m0s
[21:39:08.236] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:99:0)
[21:39:08.236] Timer tick, asking Buildbucket for the build status
[21:39:08.316] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:08.316] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:100:0) after 1m0s
[21:40:08.338] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:100:0)
[21:40:08.338] Timer tick, asking Buildbucket for the build status
[21:40:08.670] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:08.670] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:101:0) after 1m0s
[21:41:08.701] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:101:0)
[21:41:08.701] Timer tick, asking Buildbucket for the build status
[21:41:08.887] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:08.887] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:102:0) after 1m0s
[21:42:08.921] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:102:0)
[21:42:08.921] Timer tick, asking Buildbucket for the build status
[21:42:09.028] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:09.028] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:103:0) after 1m0s
[21:43:09.071] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:103:0)
[21:43:09.071] Timer tick, asking Buildbucket for the build status
[21:43:09.475] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:09.475] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:104:0) after 1m0s
[21:44:09.539] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:104:0)
[21:44:09.539] Timer tick, asking Buildbucket for the build status
[21:44:09.787] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:09.787] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:105:0) after 1m0s
[21:45:09.964] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:105:0)
[21:45:09.964] Timer tick, asking Buildbucket for the build status
[21:45:10.054] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:10.054] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:106:0) after 1m0s
[21:46:10.082] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:106:0)
[21:46:10.082] Timer tick, asking Buildbucket for the build status
[21:46:10.525] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:10.525] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:107:0) after 1m0s
[21:47:10.505] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:107:0)
[21:47:10.505] Timer tick, asking Buildbucket for the build status
[21:47:10.660] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:10.660] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:108:0) after 1m0s
[21:48:10.687] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:108:0)
[21:48:10.687] Timer tick, asking Buildbucket for the build status
[21:48:13.300] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:13.300] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:109:0) after 1m0s
[21:49:13.317] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:109:0)
[21:49:13.317] Timer tick, asking Buildbucket for the build status
[21:49:13.370] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:13.370] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:110:0) after 1m0s
[21:50:13.404] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:110:0)
[21:50:13.404] Timer tick, asking Buildbucket for the build status
[21:50:13.492] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:13.492] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:111:0) after 1m0s
[21:51:13.530] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:111:0)
[21:51:13.530] Timer tick, asking Buildbucket for the build status
[21:51:13.595] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:13.595] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:112:0) after 1m0s
[21:52:13.699] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:112:0)
[21:52:13.699] Timer tick, asking Buildbucket for the build status
[21:52:13.793] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:13.793] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:113:0) after 1m0s
[21:53:13.841] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:113:0)
[21:53:13.841] Timer tick, asking Buildbucket for the build status
[21:53:13.997] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:13.997] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:114:0) after 1m0s
[21:54:14.108] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:114:0)
[21:54:14.108] Timer tick, asking Buildbucket for the build status
[21:54:14.163] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:14.163] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:115:0) after 1m0s
[21:55:14.202] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:115:0)
[21:55:14.202] Timer tick, asking Buildbucket for the build status
[21:55:14.402] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:14.402] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:116:0) after 1m0s
[21:56:14.427] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:116:0)
[21:56:14.427] Timer tick, asking Buildbucket for the build status
[21:56:14.576] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:14.576] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:117:0) after 1m0s
[21:57:14.643] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:117:0)
[21:57:14.643] Timer tick, asking Buildbucket for the build status
[21:57:15.134] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:15.134] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:118:0) after 1m0s
[21:58:15.138] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:118:0)
[21:58:15.138] Timer tick, asking Buildbucket for the build status
[21:58:15.322] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:15.322] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:119:0) after 1m0s
[21:59:15.348] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:119:0)
[21:59:15.348] Timer tick, asking Buildbucket for the build status
[21:59:15.686] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:15.686] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:120:0) after 1m0s
[22:00:15.811] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:120:0)
[22:00:15.811] Timer tick, asking Buildbucket for the build status
[22:00:15.935] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:15.935] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:121:0) after 1m0s
[22:01:16.063] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:121:0)
[22:01:16.063] Timer tick, asking Buildbucket for the build status
[22:01:16.347] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:16.347] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:122:0) after 1m0s
[22:02:16.507] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:122:0)
[22:02:16.507] Timer tick, asking Buildbucket for the build status
[22:02:16.616] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:16.616] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:123:0) after 1m0s
[22:03:16.731] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:123:0)
[22:03:16.731] Timer tick, asking Buildbucket for the build status
[22:03:16.785] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:16.785] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:124:0) after 1m0s
[22:04:16.803] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:124:0)
[22:04:16.803] Timer tick, asking Buildbucket for the build status
[22:04:16.911] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:16.911] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:125:0) after 1m0s
[22:05:16.934] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:125:0)
[22:05:16.934] Timer tick, asking Buildbucket for the build status
[22:05:17.016] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:17.016] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:126:0) after 1m0s
[22:06:17.054] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:126:0)
[22:06:17.054] Timer tick, asking Buildbucket for the build status
[22:06:17.124] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:17.124] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:127:0) after 1m0s
[22:07:17.162] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:127:0)
[22:07:17.162] Timer tick, asking Buildbucket for the build status
[22:07:17.293] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:17.293] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:128:0) after 1m0s
[22:08:17.324] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:128:0)
[22:08:17.324] Timer tick, asking Buildbucket for the build status
[22:08:17.896] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:17.896] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:129:0) after 1m0s
[22:09:17.980] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:129:0)
[22:09:17.980] Timer tick, asking Buildbucket for the build status
[22:09:18.048] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:18.048] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:130:0) after 1m0s
[22:10:18.125] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:130:0)
[22:10:18.125] Timer tick, asking Buildbucket for the build status
[22:10:18.395] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:18.395] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:131:0) after 1m0s
[22:11:18.420] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:131:0)
[22:11:18.420] Timer tick, asking Buildbucket for the build status
[22:11:18.486] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:18.486] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:132:0) after 1m0s
[22:12:18.573] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:132:0)
[22:12:18.573] Timer tick, asking Buildbucket for the build status
[22:12:18.679] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:18.679] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:133:0) after 1m0s
[22:13:18.703] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:133:0)
[22:13:18.703] Timer tick, asking Buildbucket for the build status
[22:13:18.770] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:18.770] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:134:0) after 1m0s
[22:14:18.795] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:134:0)
[22:14:18.795] Timer tick, asking Buildbucket for the build status
[22:14:18.928] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:18.928] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:135:0) after 1m0s
[22:15:18.950] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:135:0)
[22:15:18.950] Timer tick, asking Buildbucket for the build status
[22:15:19.006] Build 8859912002797905632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:19.006] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025342064797216208:136:0) after 1m0s
[22:15:39.784] Received PubSub notification, asking Buildbucket for the build status
[22:15:39.861] Build 8859912002797905632: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:15:39.861] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608934539171420",
"created_by": "project:chromium",
"created_ts": "1608926499520978",
"id": "8859912002797905632",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"639424aef7a8638d42da8e23a2252ba44b6c6ae1\"}}",
"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\": \"639424aef7a8638d42da8e23a2252ba44b6c6ae1\", \"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-5-9fw2\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win32-official\", \"buildnumber\": 1194, \"got_angle_revision\": \"5e606e5bfce2b23c139c5289cbf1299844fa4b43\", \"got_dawn_revision\": \"c8d5277e86ee8c51b1dd7892b41e83eb26ac2ede\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"639424aef7a8638d42da8e23a2252ba44b6c6ae1\", \"got_revision_cp\": \"refs/heads/master@{#839331}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"c1afed6d5ce09f38a9eb7f39b34b4c087cd80503\", \"got_v8_revision_cp\": \"refs/heads/8.9.191@{#1}\", \"got_webrtc_revision\": \"a45df0b3491ea72cfcf40e21106eb046c8594223\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32879}\", \"mirrored_builders\": [\"tryserver.chromium:win32-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"639424aef7a8638d42da8e23a2252ba44b6c6ae1\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-5-9fw2\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1608926515040743",
"status": "COMPLETED",
"status_changed_ts": "1608934539517997",
"tags": [
"build_address:luci.chromium.ci/win32-official/1194",
"builder:win32-official",
"buildset:commit/git/639424aef7a8638d42da8e23a2252ba44b6c6ae1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/639424aef7a8638d42da8e23a2252ba44b6c6ae1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025342064797216208",
"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/8859912002797905632/+/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:50b44f9d71f58910",
"user_agent:luci-scheduler"
],
"updated_ts": "1608934539526444",
"url": "https://ci.chromium.org/b/8859912002797905632",
"utcnow_ts": "1608934539851770"
}
}
[22:15:39.861] Invocation finished in 2h14m1.632352589s with status SUCCEEDED