[09:14:07.098] New invocation is queued and will start shortly
[09:14:08.488] Starting the invocation (attempt 1)
[09:14:08.537] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:14:08.537] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:14:08.537] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9032992948354029264",
"parameters_json": "{\"builder_name\":\"win32-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4ecf4d41963420521a0ab697bdff8c0a527984cc\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win32-official",
"scheduler_invocation_id:9032992948354029264",
"scheduler_job_id:chromium/win32-official",
"user_agent:luci-scheduler",
"buildset:commit/git/4ecf4d41963420521a0ab697bdff8c0a527984cc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4ecf4d41963420521a0ab697bdff8c0a527984cc",
"gitiles_ref:refs/heads/master"
]
}
[09:14:09.737] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1601630048735020",
"id": "8867562885976001936",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4ecf4d41963420521a0ab697bdff8c0a527984cc\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1601630049479004",
"tags": [
"build_address:luci.chromium.ci/win32-official/652",
"builder:win32-official",
"buildset:commit/git/4ecf4d41963420521a0ab697bdff8c0a527984cc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4ecf4d41963420521a0ab697bdff8c0a527984cc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9032992948354029264",
"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/8867562885976001936/+/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": "1601630049479485",
"url": "https://ci.chromium.org/b/8867562885976001936",
"utcnow_ts": "1601630049723321"
}
}
[09:14:09.737] Task URL: https://ci.chromium.org/b/8867562885976001936
[09:14:09.737] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:2:0) after 1m0s
[09:14:48.876] Received PubSub notification, asking Buildbucket for the build status
[09:14:48.977] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:09.866] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:2:0)
[09:15:09.866] Timer tick, asking Buildbucket for the build status
[09:15:10.024] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:10.024] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:4:0) after 1m0s
[09:16:10.008] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:4:0)
[09:16:10.008] Timer tick, asking Buildbucket for the build status
[09:16:10.536] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:10.536] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:5:0) after 1m0s
[09:17:10.667] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:5:0)
[09:17:10.667] Timer tick, asking Buildbucket for the build status
[09:17:10.754] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:10.754] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:6:0) after 1m0s
[09:18:10.812] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:6:0)
[09:18:10.812] Timer tick, asking Buildbucket for the build status
[09:18:10.919] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:10.919] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:7:0) after 1m0s
[09:19:11.004] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:7:0)
[09:19:11.004] Timer tick, asking Buildbucket for the build status
[09:19:11.150] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:11.150] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:8:0) after 1m0s
[09:20:11.300] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:8:0)
[09:20:11.300] Timer tick, asking Buildbucket for the build status
[09:20:11.624] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:11.624] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:9:0) after 1m0s
[09:21:11.692] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:9:0)
[09:21:11.692] Timer tick, asking Buildbucket for the build status
[09:21:11.825] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:11.825] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:10:0) after 1m0s
[09:22:11.961] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:10:0)
[09:22:11.961] Timer tick, asking Buildbucket for the build status
[09:22:12.146] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:12.146] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:11:0) after 1m0s
[09:23:12.252] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:11:0)
[09:23:12.252] Timer tick, asking Buildbucket for the build status
[09:23:12.365] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:12.365] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:12:0) after 1m0s
[09:24:12.466] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:12:0)
[09:24:12.466] Timer tick, asking Buildbucket for the build status
[09:24:12.644] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:12.644] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:13:0) after 1m0s
[09:25:12.658] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:13:0)
[09:25:12.658] Timer tick, asking Buildbucket for the build status
[09:25:12.808] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:12.808] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:14:0) after 1m0s
[09:26:12.888] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:14:0)
[09:26:12.888] Timer tick, asking Buildbucket for the build status
[09:26:13.129] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:13.129] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:15:0) after 1m0s
[09:27:13.148] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:15:0)
[09:27:13.148] Timer tick, asking Buildbucket for the build status
[09:27:13.219] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:13.219] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:16:0) after 1m0s
[09:28:13.243] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:16:0)
[09:28:13.243] Timer tick, asking Buildbucket for the build status
[09:28:13.308] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:13.308] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:17:0) after 1m0s
[09:29:13.414] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:17:0)
[09:29:13.414] Timer tick, asking Buildbucket for the build status
[09:29:13.655] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:13.655] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:18:0) after 1m0s
[09:30:13.678] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:18:0)
[09:30:13.678] Timer tick, asking Buildbucket for the build status
[09:30:13.990] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:13.990] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:19:0) after 1m0s
[09:31:14.064] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:19:0)
[09:31:14.064] Timer tick, asking Buildbucket for the build status
[09:31:14.229] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:14.229] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:20:0) after 1m0s
[09:32:14.283] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:20:0)
[09:32:14.283] Timer tick, asking Buildbucket for the build status
[09:32:14.433] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:14.433] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:21:0) after 1m0s
[09:33:14.443] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:21:0)
[09:33:14.443] Timer tick, asking Buildbucket for the build status
[09:33:14.562] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:14.562] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:22:0) after 1m0s
[09:34:14.583] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:22:0)
[09:34:14.583] Timer tick, asking Buildbucket for the build status
[09:34:14.792] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:14.792] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:23:0) after 1m0s
[09:35:14.839] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:23:0)
[09:35:14.839] Timer tick, asking Buildbucket for the build status
[09:35:16.176] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:16.176] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:24:0) after 1m0s
[09:36:16.539] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:24:0)
[09:36:16.539] Timer tick, asking Buildbucket for the build status
[09:36:16.631] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:16.631] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:25:0) after 1m0s
[09:37:17.059] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:25:0)
[09:37:17.059] Timer tick, asking Buildbucket for the build status
[09:37:17.137] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:17.137] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:26:0) after 1m0s
[09:38:17.336] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:26:0)
[09:38:17.336] Timer tick, asking Buildbucket for the build status
[09:38:17.479] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:17.479] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:27:0) after 1m0s
[09:39:17.503] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:27:0)
[09:39:17.503] Timer tick, asking Buildbucket for the build status
[09:39:17.644] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:17.644] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:28:0) after 1m0s
[09:40:17.681] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:28:0)
[09:40:17.681] Timer tick, asking Buildbucket for the build status
[09:40:17.911] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:17.911] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:29:0) after 1m0s
[09:41:17.961] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:29:0)
[09:41:17.961] Timer tick, asking Buildbucket for the build status
[09:41:18.075] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:18.075] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:30:0) after 1m0s
[09:42:18.104] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:30:0)
[09:42:18.104] Timer tick, asking Buildbucket for the build status
[09:42:18.505] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:18.505] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:31:0) after 1m0s
[09:43:18.542] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:31:0)
[09:43:18.542] Timer tick, asking Buildbucket for the build status
[09:43:18.622] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:18.622] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:32:0) after 1m0s
[09:44:18.686] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:32:0)
[09:44:18.687] Timer tick, asking Buildbucket for the build status
[09:44:18.801] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:18.801] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:33:0) after 1m0s
[09:45:18.821] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:33:0)
[09:45:18.821] Timer tick, asking Buildbucket for the build status
[09:45:18.896] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:18.896] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:34:0) after 1m0s
[09:46:18.952] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:34:0)
[09:46:18.952] Timer tick, asking Buildbucket for the build status
[09:46:19.094] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:19.094] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:35:0) after 1m0s
[09:47:19.114] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:35:0)
[09:47:19.114] Timer tick, asking Buildbucket for the build status
[09:47:19.238] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:19.238] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:36:0) after 1m0s
[09:48:19.313] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:36:0)
[09:48:19.313] Timer tick, asking Buildbucket for the build status
[09:48:19.464] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:19.464] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:37:0) after 1m0s
[09:49:19.547] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:37:0)
[09:49:19.547] Timer tick, asking Buildbucket for the build status
[09:49:19.704] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:19.704] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:38:0) after 1m0s
[09:50:19.702] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:38:0)
[09:50:19.702] Timer tick, asking Buildbucket for the build status
[09:50:19.780] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:19.780] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:39:0) after 1m0s
[09:51:19.804] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:39:0)
[09:51:19.804] Timer tick, asking Buildbucket for the build status
[09:51:19.882] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:19.882] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:40:0) after 1m0s
[09:52:19.919] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:40:0)
[09:52:19.920] Timer tick, asking Buildbucket for the build status
[09:52:20.001] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:20.001] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:41:0) after 1m0s
[09:53:20.027] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:41:0)
[09:53:20.027] Timer tick, asking Buildbucket for the build status
[09:53:20.173] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:20.173] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:42:0) after 1m0s
[09:54:20.233] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:42:0)
[09:54:20.233] Timer tick, asking Buildbucket for the build status
[09:54:21.390] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:21.390] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:43:0) after 1m0s
[09:55:21.420] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:43:0)
[09:55:21.420] Timer tick, asking Buildbucket for the build status
[09:55:21.542] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:21.542] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:44:0) after 1m0s
[09:56:21.593] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:44:0)
[09:56:21.593] Timer tick, asking Buildbucket for the build status
[09:56:21.704] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:21.704] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:45:0) after 1m0s
[09:57:21.876] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:45:0)
[09:57:21.877] Timer tick, asking Buildbucket for the build status
[09:57:22.005] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:22.005] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:46:0) after 1m0s
[09:58:22.397] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:46:0)
[09:58:22.397] Timer tick, asking Buildbucket for the build status
[09:58:22.522] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:22.522] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:47:0) after 1m0s
[09:59:22.663] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:47:0)
[09:59:22.663] Timer tick, asking Buildbucket for the build status
[09:59:23.736] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:23.736] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:48:0) after 1m0s
[10:00:23.795] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:48:0)
[10:00:23.795] Timer tick, asking Buildbucket for the build status
[10:00:24.006] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:24.006] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:49:0) after 1m0s
[10:01:24.162] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:49:0)
[10:01:24.162] Timer tick, asking Buildbucket for the build status
[10:01:24.258] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:24.258] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:50:0) after 1m0s
[10:02:24.288] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:50:0)
[10:02:24.288] Timer tick, asking Buildbucket for the build status
[10:02:24.379] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:24.379] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:51:0) after 1m0s
[10:03:24.410] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:51:0)
[10:03:24.410] Timer tick, asking Buildbucket for the build status
[10:03:24.519] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:24.519] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:52:0) after 1m0s
[10:04:24.548] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:52:0)
[10:04:24.548] Timer tick, asking Buildbucket for the build status
[10:04:25.194] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:25.194] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:53:0) after 1m0s
[10:05:25.238] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:53:0)
[10:05:25.238] Timer tick, asking Buildbucket for the build status
[10:05:25.344] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:25.344] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:54:0) after 1m0s
[10:06:25.408] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:54:0)
[10:06:25.408] Timer tick, asking Buildbucket for the build status
[10:06:25.512] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:25.512] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:55:0) after 1m0s
[10:07:25.591] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:55:0)
[10:07:25.591] Timer tick, asking Buildbucket for the build status
[10:07:25.836] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:25.836] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:56:0) after 1m0s
[10:08:25.853] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:56:0)
[10:08:25.853] Timer tick, asking Buildbucket for the build status
[10:08:25.948] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:25.948] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:57:0) after 1m0s
[10:09:26.022] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:57:0)
[10:09:26.022] Timer tick, asking Buildbucket for the build status
[10:09:26.514] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:26.514] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:58:0) after 1m0s
[10:10:26.694] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:58:0)
[10:10:26.694] Timer tick, asking Buildbucket for the build status
[10:10:26.798] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:26.798] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:59:0) after 1m0s
[10:11:26.958] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:59:0)
[10:11:26.958] Timer tick, asking Buildbucket for the build status
[10:11:27.038] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:27.038] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:60:0) after 1m0s
[10:12:27.128] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:60:0)
[10:12:27.128] Timer tick, asking Buildbucket for the build status
[10:12:27.276] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:27.276] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:61:0) after 1m0s
[10:13:27.332] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:61:0)
[10:13:27.332] Timer tick, asking Buildbucket for the build status
[10:13:27.413] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:27.413] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:62:0) after 1m0s
[10:14:27.505] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:62:0)
[10:14:27.505] Timer tick, asking Buildbucket for the build status
[10:14:28.154] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:28.154] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:63:0) after 1m0s
[10:15:28.279] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:63:0)
[10:15:28.279] Timer tick, asking Buildbucket for the build status
[10:15:28.376] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:28.376] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:64:0) after 1m0s
[10:16:28.470] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:64:0)
[10:16:28.470] Timer tick, asking Buildbucket for the build status
[10:16:28.673] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:28.673] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:65:0) after 1m0s
[10:17:28.698] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:65:0)
[10:17:28.698] Timer tick, asking Buildbucket for the build status
[10:17:28.788] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:28.788] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:66:0) after 1m0s
[10:18:28.873] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:66:0)
[10:18:28.873] Timer tick, asking Buildbucket for the build status
[10:18:29.032] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:29.032] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:67:0) after 1m0s
[10:19:29.061] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:67:0)
[10:19:29.061] Timer tick, asking Buildbucket for the build status
[10:19:29.207] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:29.207] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:68:0) after 1m0s
[10:20:29.333] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:68:0)
[10:20:29.333] Timer tick, asking Buildbucket for the build status
[10:20:29.620] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:29.620] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:69:0) after 1m0s
[10:21:29.707] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:69:0)
[10:21:29.707] Timer tick, asking Buildbucket for the build status
[10:21:29.796] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:29.796] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:70:0) after 1m0s
[10:22:29.937] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:70:0)
[10:22:29.937] Timer tick, asking Buildbucket for the build status
[10:22:30.050] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:30.050] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:71:0) after 1m0s
[10:23:30.101] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:71:0)
[10:23:30.102] Timer tick, asking Buildbucket for the build status
[10:23:30.202] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:30.202] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:72:0) after 1m0s
[10:24:30.284] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:72:0)
[10:24:30.284] Timer tick, asking Buildbucket for the build status
[10:24:30.401] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:30.401] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:73:0) after 1m0s
[10:25:30.444] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:73:0)
[10:25:30.444] Timer tick, asking Buildbucket for the build status
[10:25:30.692] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:30.692] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:74:0) after 1m0s
[10:26:30.775] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:74:0)
[10:26:30.775] Timer tick, asking Buildbucket for the build status
[10:26:30.854] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:30.854] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:75:0) after 1m0s
[10:27:30.946] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:75:0)
[10:27:30.946] Timer tick, asking Buildbucket for the build status
[10:27:31.160] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:31.160] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:76:0) after 1m0s
[10:28:31.186] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:76:0)
[10:28:31.186] Timer tick, asking Buildbucket for the build status
[10:28:31.240] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:31.240] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:77:0) after 1m0s
[10:29:31.318] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:77:0)
[10:29:31.318] Timer tick, asking Buildbucket for the build status
[10:29:31.426] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:31.426] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:78:0) after 1m0s
[10:30:31.490] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:78:0)
[10:30:31.490] Timer tick, asking Buildbucket for the build status
[10:30:31.556] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:31.556] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:79:0) after 1m0s
[10:31:31.562] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:79:0)
[10:31:31.562] Timer tick, asking Buildbucket for the build status
[10:31:31.641] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:31.641] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:80:0) after 1m0s
[10:32:31.720] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:80:0)
[10:32:31.720] Timer tick, asking Buildbucket for the build status
[10:32:31.800] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:31.800] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:81:0) after 1m0s
[10:33:31.849] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:81:0)
[10:33:31.849] Timer tick, asking Buildbucket for the build status
[10:33:32.108] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:32.108] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:82:0) after 1m0s
[10:34:32.159] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:82:0)
[10:34:32.159] Timer tick, asking Buildbucket for the build status
[10:34:32.309] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:32.309] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:83:0) after 1m0s
[10:35:32.333] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:83:0)
[10:35:32.333] Timer tick, asking Buildbucket for the build status
[10:35:32.410] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:32.410] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:84:0) after 1m0s
[10:36:32.566] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:84:0)
[10:36:32.566] Timer tick, asking Buildbucket for the build status
[10:36:32.690] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:32.690] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:85:0) after 1m0s
[10:37:32.761] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:85:0)
[10:37:32.761] Timer tick, asking Buildbucket for the build status
[10:37:32.821] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:32.821] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:86:0) after 1m0s
[10:38:32.894] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:86:0)
[10:38:32.894] Timer tick, asking Buildbucket for the build status
[10:38:32.978] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:32.978] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:87:0) after 1m0s
[10:39:33.041] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:87:0)
[10:39:33.041] Timer tick, asking Buildbucket for the build status
[10:39:33.180] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:33.180] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:88:0) after 1m0s
[10:40:33.155] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:88:0)
[10:40:33.155] Timer tick, asking Buildbucket for the build status
[10:40:33.285] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:33.285] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:89:0) after 1m0s
[10:41:33.444] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:89:0)
[10:41:33.444] Timer tick, asking Buildbucket for the build status
[10:41:33.679] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:33.679] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:90:0) after 1m0s
[10:42:33.672] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:90:0)
[10:42:33.672] Timer tick, asking Buildbucket for the build status
[10:42:33.741] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:33.741] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:91:0) after 1m0s
[10:43:33.768] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:91:0)
[10:43:33.768] Timer tick, asking Buildbucket for the build status
[10:43:34.069] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:34.069] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:92:0) after 1m0s
[10:44:34.135] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:92:0)
[10:44:34.135] Timer tick, asking Buildbucket for the build status
[10:44:34.255] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:34.255] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:93:0) after 1m0s
[10:45:34.469] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:93:0)
[10:45:34.469] Timer tick, asking Buildbucket for the build status
[10:45:34.553] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:34.553] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:94:0) after 1m0s
[10:46:34.698] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:94:0)
[10:46:34.698] Timer tick, asking Buildbucket for the build status
[10:46:34.852] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:34.852] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:95:0) after 1m0s
[10:47:35.070] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:95:0)
[10:47:35.070] Timer tick, asking Buildbucket for the build status
[10:47:35.197] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:35.197] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:96:0) after 1m0s
[10:48:35.280] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:96:0)
[10:48:35.280] Timer tick, asking Buildbucket for the build status
[10:48:35.361] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:35.361] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:97:0) after 1m0s
[10:49:35.391] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:97:0)
[10:49:35.391] Timer tick, asking Buildbucket for the build status
[10:49:35.514] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:35.514] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:98:0) after 1m0s
[10:50:35.602] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:98:0)
[10:50:35.602] Timer tick, asking Buildbucket for the build status
[10:50:35.659] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:35.659] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:99:0) after 1m0s
[10:51:35.703] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:99:0)
[10:51:35.703] Timer tick, asking Buildbucket for the build status
[10:51:35.781] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:35.781] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:100:0) after 1m0s
[10:52:36.032] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:100:0)
[10:52:36.032] Timer tick, asking Buildbucket for the build status
[10:52:39.290] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:39.290] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:101:0) after 1m0s
[10:53:39.387] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:101:0)
[10:53:39.387] Timer tick, asking Buildbucket for the build status
[10:53:39.502] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:39.502] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:102:0) after 1m0s
[10:54:39.644] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:102:0)
[10:54:39.644] Timer tick, asking Buildbucket for the build status
[10:54:39.877] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:39.877] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:103:0) after 1m0s
[10:55:39.966] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:103:0)
[10:55:39.966] Timer tick, asking Buildbucket for the build status
[10:55:40.047] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:40.047] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:104:0) after 1m0s
[10:56:40.084] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:104:0)
[10:56:40.084] Timer tick, asking Buildbucket for the build status
[10:56:40.196] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:40.196] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:105:0) after 1m0s
[10:57:40.189] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:105:0)
[10:57:40.189] Timer tick, asking Buildbucket for the build status
[10:57:40.285] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:40.286] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:106:0) after 1m0s
[10:58:40.346] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:106:0)
[10:58:40.346] Timer tick, asking Buildbucket for the build status
[10:58:40.449] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:40.449] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:107:0) after 1m0s
[10:59:40.472] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:107:0)
[10:59:40.472] Timer tick, asking Buildbucket for the build status
[10:59:41.725] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:41.725] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:108:0) after 1m0s
[11:00:41.759] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:108:0)
[11:00:41.759] Timer tick, asking Buildbucket for the build status
[11:00:42.554] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:42.554] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:109:0) after 1m0s
[11:01:42.682] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:109:0)
[11:01:42.682] Timer tick, asking Buildbucket for the build status
[11:01:42.900] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:42.900] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:110:0) after 1m0s
[11:02:42.962] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:110:0)
[11:02:42.962] Timer tick, asking Buildbucket for the build status
[11:02:43.048] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:43.048] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:111:0) after 1m0s
[11:03:43.089] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:111:0)
[11:03:43.089] Timer tick, asking Buildbucket for the build status
[11:03:43.409] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:43.409] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:112:0) after 1m0s
[11:04:43.476] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:112:0)
[11:04:43.476] Timer tick, asking Buildbucket for the build status
[11:04:43.828] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:43.829] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:113:0) after 1m0s
[11:05:43.831] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:113:0)
[11:05:43.831] Timer tick, asking Buildbucket for the build status
[11:05:43.905] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:43.905] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:114:0) after 1m0s
[11:06:43.917] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:114:0)
[11:06:43.917] Timer tick, asking Buildbucket for the build status
[11:06:44.112] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:44.112] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:115:0) after 1m0s
[11:07:44.175] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:115:0)
[11:07:44.175] Timer tick, asking Buildbucket for the build status
[11:07:44.248] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:44.248] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:116:0) after 1m0s
[11:08:44.259] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:116:0)
[11:08:44.259] Timer tick, asking Buildbucket for the build status
[11:08:44.345] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:44.345] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:117:0) after 1m0s
[11:09:44.368] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:117:0)
[11:09:44.368] Timer tick, asking Buildbucket for the build status
[11:09:44.528] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:44.528] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:118:0) after 1m0s
[11:10:44.523] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:118:0)
[11:10:44.523] Timer tick, asking Buildbucket for the build status
[11:10:44.748] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:44.748] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:119:0) after 1m0s
[11:11:44.780] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:119:0)
[11:11:44.780] Timer tick, asking Buildbucket for the build status
[11:11:44.850] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:44.850] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:120:0) after 1m0s
[11:12:44.985] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:120:0)
[11:12:44.985] Timer tick, asking Buildbucket for the build status
[11:12:45.066] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:45.066] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:121:0) after 1m0s
[11:13:45.175] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:121:0)
[11:13:45.175] Timer tick, asking Buildbucket for the build status
[11:13:45.248] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:45.248] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:122:0) after 1m0s
[11:14:45.246] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:122:0)
[11:14:45.246] Timer tick, asking Buildbucket for the build status
[11:14:45.385] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:45.385] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:123:0) after 1m0s
[11:15:45.426] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:123:0)
[11:15:45.426] Timer tick, asking Buildbucket for the build status
[11:15:45.582] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:45.582] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:124:0) after 1m0s
[11:16:47.104] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:124:0)
[11:16:47.104] Timer tick, asking Buildbucket for the build status
[11:16:47.226] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:47.226] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:125:0) after 1m0s
[11:17:47.486] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:125:0)
[11:17:47.486] Timer tick, asking Buildbucket for the build status
[11:17:47.582] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:47.582] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:126:0) after 1m0s
[11:18:47.835] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:126:0)
[11:18:47.835] Timer tick, asking Buildbucket for the build status
[11:18:47.948] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:47.948] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:127:0) after 1m0s
[11:19:48.226] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:127:0)
[11:19:48.226] Timer tick, asking Buildbucket for the build status
[11:19:48.346] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:48.346] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:128:0) after 1m0s
[11:20:48.392] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:128:0)
[11:20:48.392] Timer tick, asking Buildbucket for the build status
[11:20:48.524] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:48.524] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:129:0) after 1m0s
[11:21:48.663] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:129:0)
[11:21:48.663] Timer tick, asking Buildbucket for the build status
[11:21:49.021] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:49.021] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:130:0) after 1m0s
[11:22:49.906] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:130:0)
[11:22:49.906] Timer tick, asking Buildbucket for the build status
[11:22:49.971] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:49.971] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:131:0) after 1m0s
[11:23:50.134] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:131:0)
[11:23:50.134] Timer tick, asking Buildbucket for the build status
[11:23:50.221] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:50.221] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:132:0) after 1m0s
[11:24:50.314] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:132:0)
[11:24:50.314] Timer tick, asking Buildbucket for the build status
[11:24:50.438] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:50.438] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:133:0) after 1m0s
[11:25:50.497] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:133:0)
[11:25:50.497] Timer tick, asking Buildbucket for the build status
[11:25:50.674] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:50.674] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:134:0) after 1m0s
[11:26:50.733] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:134:0)
[11:26:50.733] Timer tick, asking Buildbucket for the build status
[11:26:50.888] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:50.888] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:135:0) after 1m0s
[11:27:51.031] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:135:0)
[11:27:51.031] Timer tick, asking Buildbucket for the build status
[11:27:51.787] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:51.787] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:136:0) after 1m0s
[11:28:51.881] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:136:0)
[11:28:51.881] Timer tick, asking Buildbucket for the build status
[11:28:51.956] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:51.956] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:137:0) after 1m0s
[11:29:52.016] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:137:0)
[11:29:52.016] Timer tick, asking Buildbucket for the build status
[11:29:52.339] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:52.339] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:138:0) after 1m0s
[11:30:52.360] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:138:0)
[11:30:52.360] Timer tick, asking Buildbucket for the build status
[11:30:52.501] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:52.501] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:139:0) after 1m0s
[11:31:52.577] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:139:0)
[11:31:52.577] Timer tick, asking Buildbucket for the build status
[11:31:53.449] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:53.449] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:140:0) after 1m0s
[11:32:53.577] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:140:0)
[11:32:53.577] Timer tick, asking Buildbucket for the build status
[11:32:53.749] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:53.749] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:141:0) after 1m0s
[11:33:53.881] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:141:0)
[11:33:53.881] Timer tick, asking Buildbucket for the build status
[11:33:54.795] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:54.795] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:142:0) after 1m0s
[11:34:54.902] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:142:0)
[11:34:54.902] Timer tick, asking Buildbucket for the build status
[11:34:55.374] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:55.374] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:143:0) after 1m0s
[11:35:55.489] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:143:0)
[11:35:55.489] Timer tick, asking Buildbucket for the build status
[11:35:55.780] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:55.780] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:144:0) after 1m0s
[11:36:55.966] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:144:0)
[11:36:55.966] Timer tick, asking Buildbucket for the build status
[11:36:56.092] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:56.092] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:145:0) after 1m0s
[11:37:56.090] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:145:0)
[11:37:56.090] Timer tick, asking Buildbucket for the build status
[11:37:56.295] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:56.296] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:146:0) after 1m0s
[11:38:56.310] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:146:0)
[11:38:56.310] Timer tick, asking Buildbucket for the build status
[11:38:56.419] Build 8867562885976001936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:56.419] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9032992948354029264:147:0) after 1m0s
[11:39:08.614] Received PubSub notification, asking Buildbucket for the build status
[11:39:09.006] Build 8867562885976001936: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:39:09.006] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1601638747804333",
"created_by": "project:chromium",
"created_ts": "1601630048735020",
"id": "8867562885976001936",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4ecf4d41963420521a0ab697bdff8c0a527984cc\"}}",
"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\": \"4ecf4d41963420521a0ab697bdff8c0a527984cc\", \"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-9-lze2\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win32-official\", \"buildnumber\": 652, \"got_angle_revision\": \"1fd3e5d89a8f840f859fb9c379e58defe9c399ab\", \"got_dawn_revision\": \"16ebcf601d7f814ec1e017f499276b7da92d61b5\", \"got_nacl_revision\": \"69a0d6e8affc94187af10e0673592d1b238c6eb0\", \"got_revision\": \"4ecf4d41963420521a0ab697bdff8c0a527984cc\", \"got_revision_cp\": \"refs/heads/master@{#813103}\", \"got_swarming_client_revision\": \"44c13d73156581ea09b9389001e58c23a4b8d70a\", \"got_swiftshader_revision\": \"02818d1fdd05410fa9d3ffa6f9ce313a63d03dcf\", \"got_v8_revision\": \"0d81cd72688512abcbe1601015baee390c484a6a\", \"got_v8_revision_cp\": \"refs/heads/8.7.220@{#1}\", \"got_webrtc_revision\": \"5b5abd79106a1770bb6fdab9e40f7270c7d4259a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32285}\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4ecf4d41963420521a0ab697bdff8c0a527984cc\"}, \"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-9-lze2\"], \"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\": [\"5564-e41b668\"], \"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": "1601630084044590",
"status": "COMPLETED",
"status_changed_ts": "1601638748260969",
"tags": [
"build_address:luci.chromium.ci/win32-official/652",
"builder:win32-official",
"buildset:commit/git/4ecf4d41963420521a0ab697bdff8c0a527984cc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4ecf4d41963420521a0ab697bdff8c0a527984cc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9032992948354029264",
"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/8867562885976001936/+/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:4f01689e02365e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1601638748265984",
"url": "https://ci.chromium.org/b/8867562885976001936",
"utcnow_ts": "1601638748973463"
}
}
[11:39:09.006] Invocation finished in 2h25m1.923947584s with status SUCCEEDED