[06:35:00.248] New invocation is queued and will start shortly
[06:35:02.412] Starting the invocation (attempt 1)
[06:35:02.449] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:35:02.449] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:35:02.449] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9031100422662658640",
"parameters_json": "{\"builder_name\":\"win32-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"71c6e8a2c530f791e655b0be74202a05ce43c844\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win32-official",
"scheduler_invocation_id:9031100422662658640",
"scheduler_job_id:chromium/win32-official",
"user_agent:luci-scheduler",
"buildset:commit/git/71c6e8a2c530f791e655b0be74202a05ce43c844",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/71c6e8a2c530f791e655b0be74202a05ce43c844",
"gitiles_ref:refs/heads/master"
]
}
[06:35:03.066] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1603434902607717",
"id": "8865670359522421680",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"71c6e8a2c530f791e655b0be74202a05ce43c844\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1603434902939641",
"tags": [
"build_address:luci.chromium.ci/win32-official/799",
"builder:win32-official",
"buildset:commit/git/71c6e8a2c530f791e655b0be74202a05ce43c844",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/71c6e8a2c530f791e655b0be74202a05ce43c844",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9031100422662658640",
"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/8865670359522421680/+/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": "1603434902939753",
"url": "https://ci.chromium.org/b/8865670359522421680",
"utcnow_ts": "1603434903058098"
}
}
[06:35:03.066] Task URL: https://ci.chromium.org/b/8865670359522421680
[06:35:03.066] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:2:0) after 1m0s
[06:35:14.506] Received PubSub notification, asking Buildbucket for the build status
[06:35:14.581] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:03.157] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:2:0)
[06:36:03.157] Timer tick, asking Buildbucket for the build status
[06:36:03.356] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:03.356] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:4:0) after 1m0s
[06:37:03.450] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:4:0)
[06:37:03.450] Timer tick, asking Buildbucket for the build status
[06:37:03.685] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:03.685] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:5:0) after 1m0s
[06:38:03.745] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:5:0)
[06:38:03.745] Timer tick, asking Buildbucket for the build status
[06:38:04.022] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:04.022] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:6:0) after 1m0s
[06:39:04.047] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:6:0)
[06:39:04.047] Timer tick, asking Buildbucket for the build status
[06:39:04.615] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:04.615] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:7:0) after 1m0s
[06:40:04.708] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:7:0)
[06:40:04.708] Timer tick, asking Buildbucket for the build status
[06:40:04.817] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:04.817] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:8:0) after 1m0s
[06:41:04.952] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:8:0)
[06:41:04.952] Timer tick, asking Buildbucket for the build status
[06:41:05.046] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:05.046] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:9:0) after 1m0s
[06:42:05.190] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:9:0)
[06:42:05.190] Timer tick, asking Buildbucket for the build status
[06:42:05.330] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:05.330] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:10:0) after 1m0s
[06:43:05.393] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:10:0)
[06:43:05.393] Timer tick, asking Buildbucket for the build status
[06:43:05.467] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:05.467] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:11:0) after 1m0s
[06:44:05.543] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:11:0)
[06:44:05.543] Timer tick, asking Buildbucket for the build status
[06:44:05.902] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:05.903] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:12:0) after 1m0s
[06:45:06.049] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:12:0)
[06:45:06.049] Timer tick, asking Buildbucket for the build status
[06:45:06.251] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:06.251] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:13:0) after 1m0s
[06:46:06.263] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:13:0)
[06:46:06.263] Timer tick, asking Buildbucket for the build status
[06:46:06.431] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:06.431] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:14:0) after 1m0s
[06:47:06.453] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:14:0)
[06:47:06.453] Timer tick, asking Buildbucket for the build status
[06:47:06.519] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:06.519] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:15:0) after 1m0s
[06:48:06.619] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:15:0)
[06:48:06.619] Timer tick, asking Buildbucket for the build status
[06:48:06.700] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:06.700] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:16:0) after 1m0s
[06:49:06.890] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:16:0)
[06:49:06.890] Timer tick, asking Buildbucket for the build status
[06:49:07.122] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:07.122] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:17:0) after 1m0s
[06:50:07.148] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:17:0)
[06:50:07.148] Timer tick, asking Buildbucket for the build status
[06:50:07.275] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:07.275] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:18:0) after 1m0s
[06:51:07.339] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:18:0)
[06:51:07.339] Timer tick, asking Buildbucket for the build status
[06:51:07.824] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:07.824] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:19:0) after 1m0s
[06:52:07.965] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:19:0)
[06:52:07.965] Timer tick, asking Buildbucket for the build status
[06:52:08.308] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:08.308] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:20:0) after 1m0s
[06:53:08.398] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:20:0)
[06:53:08.398] Timer tick, asking Buildbucket for the build status
[06:53:08.464] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:08.464] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:21:0) after 1m0s
[06:54:08.577] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:21:0)
[06:54:08.577] Timer tick, asking Buildbucket for the build status
[06:54:08.852] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:08.852] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:22:0) after 1m0s
[06:55:08.941] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:22:0)
[06:55:08.941] Timer tick, asking Buildbucket for the build status
[06:55:09.078] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:09.078] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:23:0) after 1m0s
[06:56:09.107] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:23:0)
[06:56:09.107] Timer tick, asking Buildbucket for the build status
[06:56:09.409] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:09.409] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:24:0) after 1m0s
[06:57:09.434] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:24:0)
[06:57:09.434] Timer tick, asking Buildbucket for the build status
[06:57:09.538] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:09.538] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:25:0) after 1m0s
[06:58:09.642] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:25:0)
[06:58:09.642] Timer tick, asking Buildbucket for the build status
[06:58:09.710] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:09.710] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:26:0) after 1m0s
[06:59:09.743] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:26:0)
[06:59:09.743] Timer tick, asking Buildbucket for the build status
[06:59:10.530] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:10.530] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:27:0) after 1m0s
[07:00:10.541] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:27:0)
[07:00:10.541] Timer tick, asking Buildbucket for the build status
[07:00:11.032] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:11.032] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:28:0) after 1m0s
[07:01:11.055] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:28:0)
[07:01:11.055] Timer tick, asking Buildbucket for the build status
[07:01:11.229] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:11.229] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:29:0) after 1m0s
[07:02:11.271] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:29:0)
[07:02:11.271] Timer tick, asking Buildbucket for the build status
[07:02:11.410] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:11.410] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:30:0) after 1m0s
[07:03:11.459] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:30:0)
[07:03:11.459] Timer tick, asking Buildbucket for the build status
[07:03:11.699] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:11.699] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:31:0) after 1m0s
[07:04:11.707] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:31:0)
[07:04:11.707] Timer tick, asking Buildbucket for the build status
[07:04:12.088] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:12.088] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:32:0) after 1m0s
[07:05:12.102] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:32:0)
[07:05:12.102] Timer tick, asking Buildbucket for the build status
[07:05:12.299] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:12.299] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:33:0) after 1m0s
[07:06:12.271] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:33:0)
[07:06:12.271] Timer tick, asking Buildbucket for the build status
[07:06:12.342] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:12.342] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:34:0) after 1m0s
[07:07:12.404] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:34:0)
[07:07:12.404] Timer tick, asking Buildbucket for the build status
[07:07:12.471] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:12.471] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:35:0) after 1m0s
[07:08:12.573] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:35:0)
[07:08:12.573] Timer tick, asking Buildbucket for the build status
[07:08:12.698] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:12.699] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:36:0) after 1m0s
[07:09:12.770] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:36:0)
[07:09:12.770] Timer tick, asking Buildbucket for the build status
[07:09:12.961] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:12.961] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:37:0) after 1m0s
[07:10:12.946] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:37:0)
[07:10:12.946] Timer tick, asking Buildbucket for the build status
[07:10:13.492] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:13.492] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:38:0) after 1m0s
[07:11:13.555] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:38:0)
[07:11:13.555] Timer tick, asking Buildbucket for the build status
[07:11:14.078] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:14.078] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:39:0) after 1m0s
[07:12:14.184] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:39:0)
[07:12:14.184] Timer tick, asking Buildbucket for the build status
[07:12:14.254] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:14.255] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:40:0) after 1m0s
[07:13:14.280] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:40:0)
[07:13:14.280] Timer tick, asking Buildbucket for the build status
[07:13:14.389] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:14.389] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:41:0) after 1m0s
[07:14:14.501] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:41:0)
[07:14:14.501] Timer tick, asking Buildbucket for the build status
[07:14:14.601] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:14.601] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:42:0) after 1m0s
[07:15:14.600] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:42:0)
[07:15:14.600] Timer tick, asking Buildbucket for the build status
[07:15:14.731] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:14.732] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:43:0) after 1m0s
[07:16:14.757] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:43:0)
[07:16:14.757] Timer tick, asking Buildbucket for the build status
[07:16:14.830] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:14.830] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:44:0) after 1m0s
[07:17:14.876] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:44:0)
[07:17:14.876] Timer tick, asking Buildbucket for the build status
[07:17:14.971] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:14.971] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:45:0) after 1m0s
[07:18:14.999] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:45:0)
[07:18:14.999] Timer tick, asking Buildbucket for the build status
[07:18:15.115] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:15.115] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:46:0) after 1m0s
[07:19:15.171] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:46:0)
[07:19:15.171] Timer tick, asking Buildbucket for the build status
[07:19:15.253] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:15.253] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:47:0) after 1m0s
[07:20:15.288] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:47:0)
[07:20:15.288] Timer tick, asking Buildbucket for the build status
[07:20:15.392] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:15.392] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:48:0) after 1m0s
[07:21:15.538] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:48:0)
[07:21:15.538] Timer tick, asking Buildbucket for the build status
[07:21:15.615] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:15.615] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:49:0) after 1m0s
[07:22:15.687] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:49:0)
[07:22:15.687] Timer tick, asking Buildbucket for the build status
[07:22:15.754] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:15.754] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:50:0) after 1m0s
[07:23:15.845] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:50:0)
[07:23:15.845] Timer tick, asking Buildbucket for the build status
[07:23:15.924] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:15.924] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:51:0) after 1m0s
[07:24:16.029] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:51:0)
[07:24:16.029] Timer tick, asking Buildbucket for the build status
[07:24:16.097] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:16.097] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:52:0) after 1m0s
[07:25:16.140] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:52:0)
[07:25:16.140] Timer tick, asking Buildbucket for the build status
[07:25:16.249] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:16.249] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:53:0) after 1m0s
[07:26:16.338] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:53:0)
[07:26:16.338] Timer tick, asking Buildbucket for the build status
[07:26:16.418] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:16.418] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:54:0) after 1m0s
[07:27:16.623] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:54:0)
[07:27:16.623] Timer tick, asking Buildbucket for the build status
[07:27:16.703] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:16.703] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:55:0) after 1m0s
[07:28:16.841] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:55:0)
[07:28:16.841] Timer tick, asking Buildbucket for the build status
[07:28:16.975] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:16.975] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:56:0) after 1m0s
[07:29:16.963] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:56:0)
[07:29:16.963] Timer tick, asking Buildbucket for the build status
[07:29:17.161] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:17.161] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:57:0) after 1m0s
[07:30:17.188] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:57:0)
[07:30:17.188] Timer tick, asking Buildbucket for the build status
[07:30:17.463] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:17.463] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:58:0) after 1m0s
[07:31:17.604] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:58:0)
[07:31:17.604] Timer tick, asking Buildbucket for the build status
[07:31:17.733] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:17.733] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:59:0) after 1m0s
[07:32:17.759] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:59:0)
[07:32:17.759] Timer tick, asking Buildbucket for the build status
[07:32:18.006] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:18.006] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:60:0) after 1m0s
[07:33:18.078] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:60:0)
[07:33:18.078] Timer tick, asking Buildbucket for the build status
[07:33:18.184] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:18.184] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:61:0) after 1m0s
[07:34:18.207] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:61:0)
[07:34:18.207] Timer tick, asking Buildbucket for the build status
[07:34:18.269] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:18.269] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:62:0) after 1m0s
[07:35:18.386] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:62:0)
[07:35:18.386] Timer tick, asking Buildbucket for the build status
[07:35:18.537] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:18.537] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:63:0) after 1m0s
[07:36:18.565] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:63:0)
[07:36:18.565] Timer tick, asking Buildbucket for the build status
[07:36:18.700] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:18.700] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:64:0) after 1m0s
[07:37:18.680] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:64:0)
[07:37:18.680] Timer tick, asking Buildbucket for the build status
[07:37:18.749] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:18.749] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:65:0) after 1m0s
[07:38:18.798] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:65:0)
[07:38:18.798] Timer tick, asking Buildbucket for the build status
[07:38:18.884] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:18.884] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:66:0) after 1m0s
[07:39:19.015] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:66:0)
[07:39:19.015] Timer tick, asking Buildbucket for the build status
[07:39:19.089] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:19.089] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:67:0) after 1m0s
[07:40:19.257] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:67:0)
[07:40:19.257] Timer tick, asking Buildbucket for the build status
[07:40:19.352] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:19.352] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:68:0) after 1m0s
[07:41:19.461] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:68:0)
[07:41:19.461] Timer tick, asking Buildbucket for the build status
[07:41:19.525] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:19.525] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:69:0) after 1m0s
[07:42:19.585] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:69:0)
[07:42:19.585] Timer tick, asking Buildbucket for the build status
[07:42:19.661] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:19.661] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:70:0) after 1m0s
[07:43:19.726] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:70:0)
[07:43:19.726] Timer tick, asking Buildbucket for the build status
[07:43:19.816] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:19.816] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:71:0) after 1m0s
[07:44:20.007] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:71:0)
[07:44:20.007] Timer tick, asking Buildbucket for the build status
[07:44:20.117] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:20.119] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:72:0) after 1m0s
[07:45:20.182] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:72:0)
[07:45:20.182] Timer tick, asking Buildbucket for the build status
[07:45:20.364] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:20.364] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:73:0) after 1m0s
[07:46:20.516] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:73:0)
[07:46:20.516] Timer tick, asking Buildbucket for the build status
[07:46:21.514] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:21.514] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:74:0) after 1m0s
[07:47:21.613] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:74:0)
[07:47:21.613] Timer tick, asking Buildbucket for the build status
[07:47:21.845] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:21.845] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:75:0) after 1m0s
[07:48:22.085] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:75:0)
[07:48:22.085] Timer tick, asking Buildbucket for the build status
[07:48:22.155] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:22.155] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:76:0) after 1m0s
[07:49:22.240] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:76:0)
[07:49:22.240] Timer tick, asking Buildbucket for the build status
[07:49:22.322] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:22.322] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:77:0) after 1m0s
[07:50:22.352] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:77:0)
[07:50:22.352] Timer tick, asking Buildbucket for the build status
[07:50:22.489] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:22.489] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:78:0) after 1m0s
[07:51:22.541] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:78:0)
[07:51:22.541] Timer tick, asking Buildbucket for the build status
[07:51:22.720] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:22.720] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:79:0) after 1m0s
[07:52:22.787] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:79:0)
[07:52:22.787] Timer tick, asking Buildbucket for the build status
[07:52:22.860] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:22.860] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:80:0) after 1m0s
[07:53:22.923] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:80:0)
[07:53:22.923] Timer tick, asking Buildbucket for the build status
[07:53:22.987] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:22.987] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:81:0) after 1m0s
[07:54:23.735] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:81:0)
[07:54:23.735] Timer tick, asking Buildbucket for the build status
[07:54:23.830] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:23.830] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:82:0) after 1m0s
[07:55:24.263] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:82:0)
[07:55:24.263] Timer tick, asking Buildbucket for the build status
[07:55:24.381] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:24.381] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:83:0) after 1m0s
[07:56:24.553] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:83:0)
[07:56:24.553] Timer tick, asking Buildbucket for the build status
[07:56:24.619] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:24.619] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:84:0) after 1m0s
[07:57:24.758] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:84:0)
[07:57:24.758] Timer tick, asking Buildbucket for the build status
[07:57:24.828] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:24.828] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:85:0) after 1m0s
[07:58:24.886] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:85:0)
[07:58:24.886] Timer tick, asking Buildbucket for the build status
[07:58:24.951] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:24.951] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:86:0) after 1m0s
[07:59:25.022] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:86:0)
[07:59:25.022] Timer tick, asking Buildbucket for the build status
[07:59:25.095] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:25.095] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:87:0) after 1m0s
[08:00:25.194] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:87:0)
[08:00:25.194] Timer tick, asking Buildbucket for the build status
[08:00:25.303] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:25.303] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:88:0) after 1m0s
[08:01:25.364] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:88:0)
[08:01:25.364] Timer tick, asking Buildbucket for the build status
[08:01:25.453] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:25.453] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:89:0) after 1m0s
[08:02:25.611] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:89:0)
[08:02:25.611] Timer tick, asking Buildbucket for the build status
[08:02:25.833] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:25.833] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:90:0) after 1m0s
[08:03:25.960] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:90:0)
[08:03:25.960] Timer tick, asking Buildbucket for the build status
[08:03:26.260] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:26.260] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:91:0) after 1m0s
[08:04:26.267] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:91:0)
[08:04:26.267] Timer tick, asking Buildbucket for the build status
[08:04:26.534] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:26.534] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:92:0) after 1m0s
[08:05:26.651] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:92:0)
[08:05:26.651] Timer tick, asking Buildbucket for the build status
[08:05:26.765] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:26.765] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:93:0) after 1m0s
[08:06:26.854] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:93:0)
[08:06:26.854] Timer tick, asking Buildbucket for the build status
[08:06:26.933] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:26.933] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:94:0) after 1m0s
[08:07:26.930] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:94:0)
[08:07:26.930] Timer tick, asking Buildbucket for the build status
[08:07:27.021] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:27.021] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:95:0) after 1m0s
[08:08:27.138] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:95:0)
[08:08:27.138] Timer tick, asking Buildbucket for the build status
[08:08:27.209] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:27.209] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:96:0) after 1m0s
[08:09:27.385] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:96:0)
[08:09:27.385] Timer tick, asking Buildbucket for the build status
[08:09:27.595] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:27.595] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:97:0) after 1m0s
[08:10:27.626] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:97:0)
[08:10:27.626] Timer tick, asking Buildbucket for the build status
[08:10:27.750] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:27.750] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:98:0) after 1m0s
[08:11:27.814] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:98:0)
[08:11:27.814] Timer tick, asking Buildbucket for the build status
[08:11:27.910] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:27.910] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:99:0) after 1m0s
[08:12:27.979] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:99:0)
[08:12:27.979] Timer tick, asking Buildbucket for the build status
[08:12:28.113] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:28.113] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:100:0) after 1m0s
[08:13:28.227] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:100:0)
[08:13:28.227] Timer tick, asking Buildbucket for the build status
[08:13:28.367] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:28.367] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:101:0) after 1m0s
[08:14:28.396] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:101:0)
[08:14:28.396] Timer tick, asking Buildbucket for the build status
[08:14:28.714] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:28.714] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:102:0) after 1m0s
[08:15:28.752] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:102:0)
[08:15:28.752] Timer tick, asking Buildbucket for the build status
[08:15:28.865] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:28.865] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:103:0) after 1m0s
[08:16:28.934] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:103:0)
[08:16:28.934] Timer tick, asking Buildbucket for the build status
[08:16:28.994] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:28.994] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:104:0) after 1m0s
[08:17:29.027] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:104:0)
[08:17:29.027] Timer tick, asking Buildbucket for the build status
[08:17:29.325] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:29.325] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:105:0) after 1m0s
[08:18:29.359] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:105:0)
[08:18:29.359] Timer tick, asking Buildbucket for the build status
[08:18:29.426] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:29.426] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:106:0) after 1m0s
[08:19:29.435] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:106:0)
[08:19:29.435] Timer tick, asking Buildbucket for the build status
[08:19:29.781] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:29.781] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:107:0) after 1m0s
[08:20:29.811] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:107:0)
[08:20:29.811] Timer tick, asking Buildbucket for the build status
[08:20:29.927] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:29.927] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:108:0) after 1m0s
[08:21:29.909] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:108:0)
[08:21:29.909] Timer tick, asking Buildbucket for the build status
[08:21:29.994] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:29.994] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:109:0) after 1m0s
[08:22:30.038] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:109:0)
[08:22:30.038] Timer tick, asking Buildbucket for the build status
[08:22:30.122] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:30.122] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:110:0) after 1m0s
[08:23:30.163] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:110:0)
[08:23:30.163] Timer tick, asking Buildbucket for the build status
[08:23:30.419] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:30.419] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:111:0) after 1m0s
[08:24:30.450] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:111:0)
[08:24:30.450] Timer tick, asking Buildbucket for the build status
[08:24:30.533] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:30.533] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:112:0) after 1m0s
[08:25:30.534] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:112:0)
[08:25:30.534] Timer tick, asking Buildbucket for the build status
[08:25:30.674] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:30.674] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:113:0) after 1m0s
[08:26:31.067] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:113:0)
[08:26:31.067] Timer tick, asking Buildbucket for the build status
[08:26:31.146] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:31.146] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:114:0) after 1m0s
[08:27:31.343] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:114:0)
[08:27:31.343] Timer tick, asking Buildbucket for the build status
[08:27:31.430] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:31.430] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:115:0) after 1m0s
[08:28:31.663] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:115:0)
[08:28:31.663] Timer tick, asking Buildbucket for the build status
[08:28:31.727] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:31.727] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:116:0) after 1m0s
[08:29:31.805] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:116:0)
[08:29:31.805] Timer tick, asking Buildbucket for the build status
[08:29:31.865] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:31.865] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:117:0) after 1m0s
[08:30:32.153] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:117:0)
[08:30:32.153] Timer tick, asking Buildbucket for the build status
[08:30:32.261] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:32.261] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:118:0) after 1m0s
[08:31:32.483] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:118:0)
[08:31:32.483] Timer tick, asking Buildbucket for the build status
[08:31:32.571] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:32.572] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:119:0) after 1m0s
[08:32:32.690] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:119:0)
[08:32:32.690] Timer tick, asking Buildbucket for the build status
[08:32:32.758] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:32.758] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:120:0) after 1m0s
[08:33:32.839] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:120:0)
[08:33:32.839] Timer tick, asking Buildbucket for the build status
[08:33:32.919] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:32.919] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:121:0) after 1m0s
[08:34:33.010] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:121:0)
[08:34:33.010] Timer tick, asking Buildbucket for the build status
[08:34:33.119] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:33.119] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:122:0) after 1m0s
[08:35:33.168] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:122:0)
[08:35:33.168] Timer tick, asking Buildbucket for the build status
[08:35:33.472] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:33.472] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:123:0) after 1m0s
[08:36:33.511] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:123:0)
[08:36:33.511] Timer tick, asking Buildbucket for the build status
[08:36:33.576] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:33.576] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:124:0) after 1m0s
[08:37:33.666] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:124:0)
[08:37:33.666] Timer tick, asking Buildbucket for the build status
[08:37:33.715] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:33.715] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:125:0) after 1m0s
[08:38:33.819] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:125:0)
[08:38:33.819] Timer tick, asking Buildbucket for the build status
[08:38:34.092] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:34.092] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:126:0) after 1m0s
[08:39:34.133] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:126:0)
[08:39:34.133] Timer tick, asking Buildbucket for the build status
[08:39:34.230] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:34.230] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:127:0) after 1m0s
[08:40:34.299] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:127:0)
[08:40:34.299] Timer tick, asking Buildbucket for the build status
[08:40:34.560] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:34.560] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:128:0) after 1m0s
[08:41:34.622] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:128:0)
[08:41:34.622] Timer tick, asking Buildbucket for the build status
[08:41:34.712] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:34.712] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:129:0) after 1m0s
[08:42:34.748] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:129:0)
[08:42:34.748] Timer tick, asking Buildbucket for the build status
[08:42:34.886] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:34.886] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:130:0) after 1m0s
[08:43:34.892] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:130:0)
[08:43:34.892] Timer tick, asking Buildbucket for the build status
[08:43:34.959] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:34.959] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:131:0) after 1m0s
[08:44:35.005] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:131:0)
[08:44:35.005] Timer tick, asking Buildbucket for the build status
[08:44:35.095] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:35.095] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:132:0) after 1m0s
[08:45:35.124] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:132:0)
[08:45:35.124] Timer tick, asking Buildbucket for the build status
[08:45:35.242] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:35.242] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:133:0) after 1m0s
[08:46:35.271] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:133:0)
[08:46:35.271] Timer tick, asking Buildbucket for the build status
[08:46:35.378] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:35.378] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:134:0) after 1m0s
[08:47:35.443] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:134:0)
[08:47:35.443] Timer tick, asking Buildbucket for the build status
[08:47:35.610] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:35.610] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:135:0) after 1m0s
[08:48:35.679] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:135:0)
[08:48:35.679] Timer tick, asking Buildbucket for the build status
[08:48:36.287] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:36.287] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:136:0) after 1m0s
[08:49:36.309] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:136:0)
[08:49:36.309] Timer tick, asking Buildbucket for the build status
[08:49:36.478] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:36.479] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:137:0) after 1m0s
[08:50:36.525] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:137:0)
[08:50:36.525] Timer tick, asking Buildbucket for the build status
[08:50:36.645] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:36.645] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:138:0) after 1m0s
[08:51:36.752] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:138:0)
[08:51:36.752] Timer tick, asking Buildbucket for the build status
[08:51:36.823] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:36.823] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:139:0) after 1m0s
[08:52:36.861] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:139:0)
[08:52:36.861] Timer tick, asking Buildbucket for the build status
[08:52:37.013] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:37.013] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:140:0) after 1m0s
[08:53:37.122] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:140:0)
[08:53:37.122] Timer tick, asking Buildbucket for the build status
[08:53:37.195] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:37.195] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:141:0) after 1m0s
[08:54:37.247] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:141:0)
[08:54:37.247] Timer tick, asking Buildbucket for the build status
[08:54:37.411] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:37.411] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:142:0) after 1m0s
[08:55:37.543] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:142:0)
[08:55:37.543] Timer tick, asking Buildbucket for the build status
[08:55:38.345] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:38.345] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:143:0) after 1m0s
[08:56:38.472] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:143:0)
[08:56:38.472] Timer tick, asking Buildbucket for the build status
[08:56:38.541] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:38.541] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:144:0) after 1m0s
[08:57:38.681] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:144:0)
[08:57:38.681] Timer tick, asking Buildbucket for the build status
[08:57:38.777] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:38.777] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:145:0) after 1m0s
[08:58:39.202] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:145:0)
[08:58:39.202] Timer tick, asking Buildbucket for the build status
[08:58:39.258] Build 8865670359522421680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:39.258] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9031100422662658640:146:0) after 1m0s
[08:59:03.661] Received PubSub notification, asking Buildbucket for the build status
[08:59:03.765] Build 8865670359522421680: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:59:03.766] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1603443542901054",
"created_by": "project:chromium",
"created_ts": "1603434902607717",
"id": "8865670359522421680",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"71c6e8a2c530f791e655b0be74202a05ce43c844\"}}",
"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\": \"71c6e8a2c530f791e655b0be74202a05ce43c844\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"win-dbg-7-y7dp\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win32-official\", \"buildnumber\": 799, \"got_angle_revision\": \"5641e3c185a85c04f40441698d693773b1e9e808\", \"got_dawn_revision\": \"2ce1d927e98a654bb9b7137a07a7ccf5dbb5f4df\", \"got_nacl_revision\": \"0fabd6dc4222dfae664e55a84326e0c904f5b62c\", \"got_revision\": \"71c6e8a2c530f791e655b0be74202a05ce43c844\", \"got_revision_cp\": \"refs/heads/master@{#820116}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"df17a76102dfabb3f1bd6e51449cece9f77b45e3\", \"got_v8_revision\": \"d9ccb94fc2738d2c7bec626c287518c5e3ad14e9\", \"got_v8_revision_cp\": \"refs/heads/8.8.149@{#1}\", \"got_webrtc_revision\": \"7bc3356fbcb9b95ea2c810ae2f3152930d54577b\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32449}\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"71c6e8a2c530f791e655b0be74202a05ce43c844\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_f6b49936a2965b3cdad8f63ce13313d0c4b82119a9acfffbfe199e91c26d0c88_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-7-y7dp\"], \"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\": [\"5612-26d29a7\"], \"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": "1603434913178807",
"status": "COMPLETED",
"status_changed_ts": "1603443543238934",
"tags": [
"build_address:luci.chromium.ci/win32-official/799",
"builder:win32-official",
"buildset:commit/git/71c6e8a2c530f791e655b0be74202a05ce43c844",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/71c6e8a2c530f791e655b0be74202a05ce43c844",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9031100422662658640",
"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/8865670359522421680/+/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:4f6cfc7f5593cd10",
"user_agent:luci-scheduler"
],
"updated_ts": "1603443543271986",
"url": "https://ci.chromium.org/b/8865670359522421680",
"utcnow_ts": "1603443543754540"
}
}
[08:59:03.766] Invocation finished in 2h24m3.538079205s with status SUCCEEDED