[04:28:50.331] New invocation is queued and will start shortly
[04:28:51.529] Starting the invocation (attempt 1)
[04:28:51.580] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:28:51.580] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:28:51.580] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9037812535805953152",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4430c2c58af2949e5cbd964beb39e68a254628b0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9037812535805953152",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/4430c2c58af2949e5cbd964beb39e68a254628b0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4430c2c58af2949e5cbd964beb39e68a254628b0",
"gitiles_ref:refs/heads/master"
]
}
[04:28:52.701] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1597033731704147",
"id": "8872382473703324656",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4430c2c58af2949e5cbd964beb39e68a254628b0\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1597033732435798",
"tags": [
"build_address:luci.chromium.ci/win-official/304",
"builder:win-official",
"buildset:commit/git/4430c2c58af2949e5cbd964beb39e68a254628b0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4430c2c58af2949e5cbd964beb39e68a254628b0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037812535805953152",
"scheduler_job_id:chromium/win-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8872382473703324656/+/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": "1597033732435968",
"url": "https://ci.chromium.org/b/8872382473703324656",
"utcnow_ts": "1597033732689994"
}
}
[04:28:52.701] Task URL: https://ci.chromium.org/b/8872382473703324656
[04:28:52.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:2:0) after 1m0s
[04:28:57.985] Received PubSub notification, asking Buildbucket for the build status
[04:28:58.074] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:52.768] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:2:0)
[04:29:52.768] Timer tick, asking Buildbucket for the build status
[04:29:52.995] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:52.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:4:0) after 1m0s
[04:30:53.025] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:4:0)
[04:30:53.025] Timer tick, asking Buildbucket for the build status
[04:30:53.339] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:53.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:5:0) after 1m0s
[04:31:53.391] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:5:0)
[04:31:53.391] Timer tick, asking Buildbucket for the build status
[04:31:53.516] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:53.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:6:0) after 1m0s
[04:32:53.532] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:6:0)
[04:32:53.532] Timer tick, asking Buildbucket for the build status
[04:32:53.800] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:53.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:7:0) after 1m0s
[04:33:53.828] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:7:0)
[04:33:53.828] Timer tick, asking Buildbucket for the build status
[04:33:54.120] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:54.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:8:0) after 1m0s
[04:34:54.293] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:8:0)
[04:34:54.293] Timer tick, asking Buildbucket for the build status
[04:34:54.402] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:54.402] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:9:0) after 1m0s
[04:35:54.508] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:9:0)
[04:35:54.508] Timer tick, asking Buildbucket for the build status
[04:35:54.599] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:54.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:10:0) after 1m0s
[04:36:54.680] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:10:0)
[04:36:54.680] Timer tick, asking Buildbucket for the build status
[04:36:54.858] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:54.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:11:0) after 1m0s
[04:37:54.836] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:11:0)
[04:37:54.836] Timer tick, asking Buildbucket for the build status
[04:37:55.057] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:55.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:12:0) after 1m0s
[04:38:55.185] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:12:0)
[04:38:55.185] Timer tick, asking Buildbucket for the build status
[04:38:55.500] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:55.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:13:0) after 1m0s
[04:39:55.551] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:13:0)
[04:39:55.551] Timer tick, asking Buildbucket for the build status
[04:39:55.686] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:55.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:14:0) after 1m0s
[04:40:55.743] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:14:0)
[04:40:55.743] Timer tick, asking Buildbucket for the build status
[04:40:55.841] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:55.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:15:0) after 1m0s
[04:41:55.904] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:15:0)
[04:41:55.904] Timer tick, asking Buildbucket for the build status
[04:41:56.312] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:56.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:16:0) after 1m0s
[04:42:56.424] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:16:0)
[04:42:56.424] Timer tick, asking Buildbucket for the build status
[04:42:56.547] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:56.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:17:0) after 1m0s
[04:43:56.693] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:17:0)
[04:43:56.693] Timer tick, asking Buildbucket for the build status
[04:43:56.818] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:56.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:18:0) after 1m0s
[04:44:56.862] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:18:0)
[04:44:56.862] Timer tick, asking Buildbucket for the build status
[04:44:56.984] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:56.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:19:0) after 1m0s
[04:45:57.117] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:19:0)
[04:45:57.117] Timer tick, asking Buildbucket for the build status
[04:45:58.217] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:58.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:20:0) after 1m0s
[04:46:58.271] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:20:0)
[04:46:58.271] Timer tick, asking Buildbucket for the build status
[04:46:58.353] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:58.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:21:0) after 1m0s
[04:47:58.464] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:21:0)
[04:47:58.464] Timer tick, asking Buildbucket for the build status
[04:47:58.531] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:58.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:22:0) after 1m0s
[04:48:58.538] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:22:0)
[04:48:58.538] Timer tick, asking Buildbucket for the build status
[04:48:58.675] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:58.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:23:0) after 1m0s
[04:49:58.751] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:23:0)
[04:49:58.751] Timer tick, asking Buildbucket for the build status
[04:49:58.826] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:58.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:24:0) after 1m0s
[04:50:58.900] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:24:0)
[04:50:58.900] Timer tick, asking Buildbucket for the build status
[04:50:59.004] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:59.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:25:0) after 1m0s
[04:51:59.136] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:25:0)
[04:51:59.136] Timer tick, asking Buildbucket for the build status
[04:51:59.398] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:59.398] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:26:0) after 1m0s
[04:52:59.417] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:26:0)
[04:52:59.417] Timer tick, asking Buildbucket for the build status
[04:52:59.469] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:59.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:27:0) after 1m0s
[04:53:59.454] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:27:0)
[04:53:59.454] Timer tick, asking Buildbucket for the build status
[04:53:59.600] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:59.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:28:0) after 1m0s
[04:54:59.626] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:28:0)
[04:54:59.626] Timer tick, asking Buildbucket for the build status
[04:54:59.811] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:59.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:29:0) after 1m0s
[04:55:59.969] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:29:0)
[04:55:59.969] Timer tick, asking Buildbucket for the build status
[04:56:00.219] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:00.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:30:0) after 1m0s
[04:57:00.305] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:30:0)
[04:57:00.305] Timer tick, asking Buildbucket for the build status
[04:57:00.368] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:00.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:31:0) after 1m0s
[04:58:01.882] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:31:0)
[04:58:01.882] Timer tick, asking Buildbucket for the build status
[04:58:02.089] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:02.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:32:0) after 1m0s
[04:59:02.170] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:32:0)
[04:59:02.170] Timer tick, asking Buildbucket for the build status
[04:59:02.598] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:02.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:33:0) after 1m0s
[05:00:02.611] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:33:0)
[05:00:02.611] Timer tick, asking Buildbucket for the build status
[05:00:03.246] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:03.246] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:34:0) after 1m0s
[05:01:03.299] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:34:0)
[05:01:03.299] Timer tick, asking Buildbucket for the build status
[05:01:03.458] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:03.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:35:0) after 1m0s
[05:02:03.494] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:35:0)
[05:02:03.494] Timer tick, asking Buildbucket for the build status
[05:02:03.593] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:03.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:36:0) after 1m0s
[05:03:03.621] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:36:0)
[05:03:03.621] Timer tick, asking Buildbucket for the build status
[05:03:04.011] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:04.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:37:0) after 1m0s
[05:04:04.110] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:37:0)
[05:04:04.110] Timer tick, asking Buildbucket for the build status
[05:04:04.250] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:04.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:38:0) after 1m0s
[05:05:04.393] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:38:0)
[05:05:04.393] Timer tick, asking Buildbucket for the build status
[05:05:04.479] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:04.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:39:0) after 1m0s
[05:06:04.577] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:39:0)
[05:06:04.577] Timer tick, asking Buildbucket for the build status
[05:06:04.646] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:04.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:40:0) after 1m0s
[05:07:04.761] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:40:0)
[05:07:04.761] Timer tick, asking Buildbucket for the build status
[05:07:04.843] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:04.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:41:0) after 1m0s
[05:08:05.023] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:41:0)
[05:08:05.023] Timer tick, asking Buildbucket for the build status
[05:08:05.242] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:05.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:42:0) after 1m0s
[05:09:05.301] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:42:0)
[05:09:05.301] Timer tick, asking Buildbucket for the build status
[05:09:05.408] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:05.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:43:0) after 1m0s
[05:10:05.521] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:43:0)
[05:10:05.521] Timer tick, asking Buildbucket for the build status
[05:10:05.595] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:05.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:44:0) after 1m0s
[05:11:05.669] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:44:0)
[05:11:05.669] Timer tick, asking Buildbucket for the build status
[05:11:05.906] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:05.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:45:0) after 1m0s
[05:12:05.992] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:45:0)
[05:12:05.992] Timer tick, asking Buildbucket for the build status
[05:12:06.083] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:06.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:46:0) after 1m0s
[05:13:06.146] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:46:0)
[05:13:06.146] Timer tick, asking Buildbucket for the build status
[05:13:06.549] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:06.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:47:0) after 1m0s
[05:14:06.599] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:47:0)
[05:14:06.599] Timer tick, asking Buildbucket for the build status
[05:14:07.305] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:07.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:48:0) after 1m0s
[05:15:07.325] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:48:0)
[05:15:07.325] Timer tick, asking Buildbucket for the build status
[05:15:07.419] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:07.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:49:0) after 1m0s
[05:16:07.480] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:49:0)
[05:16:07.480] Timer tick, asking Buildbucket for the build status
[05:16:07.552] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:07.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:50:0) after 1m0s
[05:17:07.642] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:50:0)
[05:17:07.642] Timer tick, asking Buildbucket for the build status
[05:17:07.821] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:07.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:51:0) after 1m0s
[05:18:07.930] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:51:0)
[05:18:07.930] Timer tick, asking Buildbucket for the build status
[05:18:08.112] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:08.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:52:0) after 1m0s
[05:19:08.192] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:52:0)
[05:19:08.192] Timer tick, asking Buildbucket for the build status
[05:19:08.497] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:08.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:53:0) after 1m0s
[05:20:08.524] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:53:0)
[05:20:08.524] Timer tick, asking Buildbucket for the build status
[05:20:08.643] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:08.643] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:54:0) after 1m0s
[05:21:08.774] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:54:0)
[05:21:08.774] Timer tick, asking Buildbucket for the build status
[05:21:08.914] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:08.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:55:0) after 1m0s
[05:22:09.003] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:55:0)
[05:22:09.003] Timer tick, asking Buildbucket for the build status
[05:22:09.077] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:09.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:56:0) after 1m0s
[05:23:09.204] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:56:0)
[05:23:09.204] Timer tick, asking Buildbucket for the build status
[05:23:09.291] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:09.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:57:0) after 1m0s
[05:24:09.366] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:57:0)
[05:24:09.366] Timer tick, asking Buildbucket for the build status
[05:24:09.583] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:09.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:58:0) after 1m0s
[05:25:09.644] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:58:0)
[05:25:09.644] Timer tick, asking Buildbucket for the build status
[05:25:09.814] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:09.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:59:0) after 1m0s
[05:26:09.845] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:59:0)
[05:26:09.845] Timer tick, asking Buildbucket for the build status
[05:26:10.120] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:10.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:60:0) after 1m0s
[05:27:10.145] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:60:0)
[05:27:10.145] Timer tick, asking Buildbucket for the build status
[05:27:10.218] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:10.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:61:0) after 1m0s
[05:28:10.219] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:61:0)
[05:28:10.219] Timer tick, asking Buildbucket for the build status
[05:28:10.310] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:10.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:62:0) after 1m0s
[05:29:10.393] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:62:0)
[05:29:10.393] Timer tick, asking Buildbucket for the build status
[05:29:11.465] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:11.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:63:0) after 1m0s
[05:30:11.598] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:63:0)
[05:30:11.598] Timer tick, asking Buildbucket for the build status
[05:30:11.674] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:11.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:64:0) after 1m0s
[05:31:11.706] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:64:0)
[05:31:11.706] Timer tick, asking Buildbucket for the build status
[05:31:11.902] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:11.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:65:0) after 1m0s
[05:32:11.890] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:65:0)
[05:32:11.890] Timer tick, asking Buildbucket for the build status
[05:32:11.986] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:11.986] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:66:0) after 1m0s
[05:33:12.028] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:66:0)
[05:33:12.028] Timer tick, asking Buildbucket for the build status
[05:33:12.102] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:12.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:67:0) after 1m0s
[05:34:12.127] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:67:0)
[05:34:12.127] Timer tick, asking Buildbucket for the build status
[05:34:12.374] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:12.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:68:0) after 1m0s
[05:35:12.447] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:68:0)
[05:35:12.447] Timer tick, asking Buildbucket for the build status
[05:35:12.547] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:12.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:69:0) after 1m0s
[05:36:12.646] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:69:0)
[05:36:12.646] Timer tick, asking Buildbucket for the build status
[05:36:12.720] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:12.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:70:0) after 1m0s
[05:37:12.746] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:70:0)
[05:37:12.746] Timer tick, asking Buildbucket for the build status
[05:37:12.839] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:12.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:71:0) after 1m0s
[05:38:12.871] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:71:0)
[05:38:12.871] Timer tick, asking Buildbucket for the build status
[05:38:12.964] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:12.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:72:0) after 1m0s
[05:39:12.992] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:72:0)
[05:39:12.992] Timer tick, asking Buildbucket for the build status
[05:39:13.252] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:13.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:73:0) after 1m0s
[05:40:13.279] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:73:0)
[05:40:13.279] Timer tick, asking Buildbucket for the build status
[05:40:13.449] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:13.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:74:0) after 1m0s
[05:41:13.537] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:74:0)
[05:41:13.537] Timer tick, asking Buildbucket for the build status
[05:41:13.617] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:13.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:75:0) after 1m0s
[05:42:13.751] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:75:0)
[05:42:13.751] Timer tick, asking Buildbucket for the build status
[05:42:13.809] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:13.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:76:0) after 1m0s
[05:43:13.868] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:76:0)
[05:43:13.868] Timer tick, asking Buildbucket for the build status
[05:43:14.092] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:14.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:77:0) after 1m0s
[05:44:14.174] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:77:0)
[05:44:14.174] Timer tick, asking Buildbucket for the build status
[05:44:14.322] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:14.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:78:0) after 1m0s
[05:45:14.350] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:78:0)
[05:45:14.350] Timer tick, asking Buildbucket for the build status
[05:45:14.435] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:14.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:79:0) after 1m0s
[05:46:14.453] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:79:0)
[05:46:14.453] Timer tick, asking Buildbucket for the build status
[05:46:14.647] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:14.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:80:0) after 1m0s
[05:47:14.717] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:80:0)
[05:47:14.717] Timer tick, asking Buildbucket for the build status
[05:47:14.804] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:14.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:81:0) after 1m0s
[05:48:14.876] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:81:0)
[05:48:14.876] Timer tick, asking Buildbucket for the build status
[05:48:14.960] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:14.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:82:0) after 1m0s
[05:49:15.016] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:82:0)
[05:49:15.016] Timer tick, asking Buildbucket for the build status
[05:49:15.247] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:15.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:83:0) after 1m0s
[05:50:15.274] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:83:0)
[05:50:15.274] Timer tick, asking Buildbucket for the build status
[05:50:15.360] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:15.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:84:0) after 1m0s
[05:51:15.389] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:84:0)
[05:51:15.389] Timer tick, asking Buildbucket for the build status
[05:51:15.512] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:15.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:85:0) after 1m0s
[05:52:15.509] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:85:0)
[05:52:15.509] Timer tick, asking Buildbucket for the build status
[05:52:15.715] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:15.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:86:0) after 1m0s
[05:53:15.758] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:86:0)
[05:53:15.758] Timer tick, asking Buildbucket for the build status
[05:53:15.895] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:15.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:87:0) after 1m0s
[05:54:15.907] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:87:0)
[05:54:15.907] Timer tick, asking Buildbucket for the build status
[05:54:16.136] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:16.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:88:0) after 1m0s
[05:55:16.186] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:88:0)
[05:55:16.186] Timer tick, asking Buildbucket for the build status
[05:55:16.349] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:16.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:89:0) after 1m0s
[05:56:16.393] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:89:0)
[05:56:16.393] Timer tick, asking Buildbucket for the build status
[05:56:16.484] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:16.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:90:0) after 1m0s
[05:57:16.519] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:90:0)
[05:57:16.519] Timer tick, asking Buildbucket for the build status
[05:57:16.732] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:16.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:91:0) after 1m0s
[05:58:16.791] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:91:0)
[05:58:16.791] Timer tick, asking Buildbucket for the build status
[05:58:17.054] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:17.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:92:0) after 1m0s
[05:59:17.088] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:92:0)
[05:59:17.088] Timer tick, asking Buildbucket for the build status
[05:59:17.387] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:17.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:93:0) after 1m0s
[06:00:17.526] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:93:0)
[06:00:17.526] Timer tick, asking Buildbucket for the build status
[06:00:17.663] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:17.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:94:0) after 1m0s
[06:01:17.794] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:94:0)
[06:01:17.794] Timer tick, asking Buildbucket for the build status
[06:01:17.877] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:17.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:95:0) after 1m0s
[06:02:17.924] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:95:0)
[06:02:17.924] Timer tick, asking Buildbucket for the build status
[06:02:18.003] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:18.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:96:0) after 1m0s
[06:03:17.978] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:96:0)
[06:03:17.978] Timer tick, asking Buildbucket for the build status
[06:03:18.077] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:18.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:97:0) after 1m0s
[06:04:18.102] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:97:0)
[06:04:18.102] Timer tick, asking Buildbucket for the build status
[06:04:18.318] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:18.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:98:0) after 1m0s
[06:05:18.346] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:98:0)
[06:05:18.346] Timer tick, asking Buildbucket for the build status
[06:05:18.560] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:18.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:99:0) after 1m0s
[06:06:18.591] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:99:0)
[06:06:18.591] Timer tick, asking Buildbucket for the build status
[06:06:18.690] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:18.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:100:0) after 1m0s
[06:07:18.717] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:100:0)
[06:07:18.717] Timer tick, asking Buildbucket for the build status
[06:07:18.798] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:18.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:101:0) after 1m0s
[06:08:18.857] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:101:0)
[06:08:18.857] Timer tick, asking Buildbucket for the build status
[06:08:19.006] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:19.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:102:0) after 1m0s
[06:09:19.037] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:102:0)
[06:09:19.037] Timer tick, asking Buildbucket for the build status
[06:09:19.172] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:19.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:103:0) after 1m0s
[06:10:19.252] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:103:0)
[06:10:19.252] Timer tick, asking Buildbucket for the build status
[06:10:19.330] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:19.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:104:0) after 1m0s
[06:11:19.355] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:104:0)
[06:11:19.355] Timer tick, asking Buildbucket for the build status
[06:11:19.632] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:19.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:105:0) after 1m0s
[06:12:19.746] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:105:0)
[06:12:19.746] Timer tick, asking Buildbucket for the build status
[06:12:19.812] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:19.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:106:0) after 1m0s
[06:13:19.889] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:106:0)
[06:13:19.889] Timer tick, asking Buildbucket for the build status
[06:13:20.094] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:20.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:107:0) after 1m0s
[06:14:20.118] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:107:0)
[06:14:20.118] Timer tick, asking Buildbucket for the build status
[06:14:20.622] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:20.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:108:0) after 1m0s
[06:15:20.651] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:108:0)
[06:15:20.651] Timer tick, asking Buildbucket for the build status
[06:15:20.850] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:20.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:109:0) after 1m0s
[06:16:20.927] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:109:0)
[06:16:20.927] Timer tick, asking Buildbucket for the build status
[06:16:21.051] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:21.051] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:110:0) after 1m0s
[06:17:21.114] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:110:0)
[06:17:21.114] Timer tick, asking Buildbucket for the build status
[06:17:21.235] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:21.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:111:0) after 1m0s
[06:18:21.223] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:111:0)
[06:18:21.223] Timer tick, asking Buildbucket for the build status
[06:18:21.443] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:21.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:112:0) after 1m0s
[06:19:21.468] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:112:0)
[06:19:21.469] Timer tick, asking Buildbucket for the build status
[06:19:21.591] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:21.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:113:0) after 1m0s
[06:20:21.643] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:113:0)
[06:20:21.643] Timer tick, asking Buildbucket for the build status
[06:20:21.744] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:21.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:114:0) after 1m0s
[06:21:21.774] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:114:0)
[06:21:21.774] Timer tick, asking Buildbucket for the build status
[06:21:22.065] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:22.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:115:0) after 1m0s
[06:22:22.093] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:115:0)
[06:22:22.093] Timer tick, asking Buildbucket for the build status
[06:22:22.329] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:22.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:116:0) after 1m0s
[06:23:22.364] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:116:0)
[06:23:22.364] Timer tick, asking Buildbucket for the build status
[06:23:22.457] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:22.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:117:0) after 1m0s
[06:24:22.488] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:117:0)
[06:24:22.488] Timer tick, asking Buildbucket for the build status
[06:24:22.702] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:22.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:118:0) after 1m0s
[06:25:22.779] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:118:0)
[06:25:22.779] Timer tick, asking Buildbucket for the build status
[06:25:23.023] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:23.023] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:119:0) after 1m0s
[06:26:23.046] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:119:0)
[06:26:23.046] Timer tick, asking Buildbucket for the build status
[06:26:23.188] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:23.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:120:0) after 1m0s
[06:27:23.265] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:120:0)
[06:27:23.265] Timer tick, asking Buildbucket for the build status
[06:27:23.514] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:23.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:121:0) after 1m0s
[06:28:23.587] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:121:0)
[06:28:23.587] Timer tick, asking Buildbucket for the build status
[06:28:23.718] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:23.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:122:0) after 1m0s
[06:29:23.743] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:122:0)
[06:29:23.743] Timer tick, asking Buildbucket for the build status
[06:29:23.873] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:23.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:123:0) after 1m0s
[06:30:23.942] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:123:0)
[06:30:23.942] Timer tick, asking Buildbucket for the build status
[06:30:24.018] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:24.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:124:0) after 1m0s
[06:31:24.098] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:124:0)
[06:31:24.098] Timer tick, asking Buildbucket for the build status
[06:31:24.392] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:24.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:125:0) after 1m0s
[06:32:24.429] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:125:0)
[06:32:24.429] Timer tick, asking Buildbucket for the build status
[06:32:24.508] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:24.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:126:0) after 1m0s
[06:33:24.535] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:126:0)
[06:33:24.535] Timer tick, asking Buildbucket for the build status
[06:33:24.672] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:24.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:127:0) after 1m0s
[06:34:24.696] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:127:0)
[06:34:24.696] Timer tick, asking Buildbucket for the build status
[06:34:24.908] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:24.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:128:0) after 1m0s
[06:35:24.947] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:128:0)
[06:35:24.947] Timer tick, asking Buildbucket for the build status
[06:35:25.052] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:25.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:129:0) after 1m0s
[06:36:25.175] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:129:0)
[06:36:25.175] Timer tick, asking Buildbucket for the build status
[06:36:25.575] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:25.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:130:0) after 1m0s
[06:37:25.609] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:130:0)
[06:37:25.609] Timer tick, asking Buildbucket for the build status
[06:37:25.721] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:25.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:131:0) after 1m0s
[06:38:25.864] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:131:0)
[06:38:25.864] Timer tick, asking Buildbucket for the build status
[06:38:25.982] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:25.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:132:0) after 1m0s
[06:39:26.067] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:132:0)
[06:39:26.068] Timer tick, asking Buildbucket for the build status
[06:39:26.188] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:26.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:133:0) after 1m0s
[06:40:26.902] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:133:0)
[06:40:26.902] Timer tick, asking Buildbucket for the build status
[06:40:26.985] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:26.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:134:0) after 1m0s
[06:41:27.126] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:134:0)
[06:41:27.126] Timer tick, asking Buildbucket for the build status
[06:41:27.245] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:27.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:135:0) after 1m0s
[06:42:27.268] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:135:0)
[06:42:27.268] Timer tick, asking Buildbucket for the build status
[06:42:27.352] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:27.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:136:0) after 1m0s
[06:43:27.501] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:136:0)
[06:43:27.501] Timer tick, asking Buildbucket for the build status
[06:43:27.599] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:27.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:137:0) after 1m0s
[06:44:27.976] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:137:0)
[06:44:27.976] Timer tick, asking Buildbucket for the build status
[06:44:28.314] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:28.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:138:0) after 1m0s
[06:45:28.421] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:138:0)
[06:45:28.421] Timer tick, asking Buildbucket for the build status
[06:45:28.518] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:28.518] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:139:0) after 1m0s
[06:46:28.695] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:139:0)
[06:46:28.695] Timer tick, asking Buildbucket for the build status
[06:46:29.494] Build 8872382473703324656: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:29.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037812535805953152:140:0) after 1m0s
[06:47:04.918] Received PubSub notification, asking Buildbucket for the build status
[06:47:04.989] Build 8872382473703324656: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[06:47:04.989] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1597042024113801",
"created_by": "project:chromium",
"created_ts": "1597033731704147",
"id": "8872382473703324656",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4430c2c58af2949e5cbd964beb39e68a254628b0\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/chromium_tests\": {\"bucketed_triggers\": true}, \"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"win-dbg-0-j9yd\", \"branch\": \"refs/heads/master\", \"buildername\": \"win-official\", \"buildnumber\": 304, \"got_angle_revision\": \"48ba75ac6845428971ea2a12bfb69eb85e608409\", \"got_dawn_revision\": \"1662e97f324ef120cf097ba38d63a69cad83593c\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"4430c2c58af2949e5cbd964beb39e68a254628b0\", \"got_revision_cp\": \"refs/heads/master@{#796293}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"59b4828f293eb16f8983ae9ea0a8a0a84cf8174c\", \"got_v8_revision\": \"4f52dde736e657ac01cf74a6cdc38c752f9a5dae\", \"got_v8_revision_cp\": \"refs/heads/8.6.337@{#1}\", \"got_webrtc_revision\": \"4c2f9c90f42591f0935b496d61ee256ebf53198f\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31887}\", \"mastername\": \"chromium\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4430c2c58af2949e5cbd964beb39e68a254628b0\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-0-j9yd\"], \"image\": [\"chrome-win10-1703-20073100-2eb11ddbc6e\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5472-d0af7f3\"], \"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": "1597033734357311",
"status": "COMPLETED",
"status_changed_ts": "1597042024579644",
"tags": [
"build_address:luci.chromium.ci/win-official/304",
"builder:win-official",
"buildset:commit/git/4430c2c58af2949e5cbd964beb39e68a254628b0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4430c2c58af2949e5cbd964beb39e68a254628b0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037812535805953152",
"scheduler_job_id:chromium/win-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8872382473703324656/+/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:4def7262dd8dc510",
"user_agent:luci-scheduler"
],
"updated_ts": "1597042024601832",
"url": "https://ci.chromium.org/b/8872382473703324656",
"utcnow_ts": "1597042024982104"
}
}
[06:47:04.989] Invocation finished in 2h18m14.674564512s with status SUCCEEDED