[04:57:48.309] New invocation is queued and will start shortly
[04:57:49.519] Starting the invocation (attempt 1)
[04:57:49.561] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:57:49.561] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:57:49.561] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029566389463761824",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5f273f29447579cad34cbfee84e78b2e7d9187be\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9029566389463761824",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/5f273f29447579cad34cbfee84e78b2e7d9187be",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5f273f29447579cad34cbfee84e78b2e7d9187be",
"gitiles_ref:refs/heads/master"
]
}
[04:57:50.407] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604897869655227",
"id": "8864136327387083136",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5f273f29447579cad34cbfee84e78b2e7d9187be\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604897870125340",
"tags": [
"build_address:luci.chromium.ci/win-official/994",
"builder:win-official",
"buildset:commit/git/5f273f29447579cad34cbfee84e78b2e7d9187be",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5f273f29447579cad34cbfee84e78b2e7d9187be",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029566389463761824",
"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/8864136327387083136/+/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": "1604897870125502",
"url": "https://ci.chromium.org/b/8864136327387083136",
"utcnow_ts": "1604897870343391"
}
}
[04:57:50.407] Task URL: https://ci.chromium.org/b/8864136327387083136
[04:57:50.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:2:0) after 1m0s
[04:58:32.080] Received PubSub notification, asking Buildbucket for the build status
[04:58:32.152] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:51.299] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:2:0)
[04:58:51.299] Timer tick, asking Buildbucket for the build status
[04:58:51.374] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:51.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:4:0) after 1m0s
[04:59:51.487] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:4:0)
[04:59:51.487] Timer tick, asking Buildbucket for the build status
[04:59:51.622] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:51.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:5:0) after 1m0s
[05:00:51.822] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:5:0)
[05:00:51.822] Timer tick, asking Buildbucket for the build status
[05:00:51.980] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:51.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:6:0) after 1m0s
[05:01:52.348] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:6:0)
[05:01:52.348] Timer tick, asking Buildbucket for the build status
[05:01:52.412] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:52.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:7:0) after 1m0s
[05:02:52.633] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:7:0)
[05:02:52.633] Timer tick, asking Buildbucket for the build status
[05:02:52.714] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:52.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:8:0) after 1m0s
[05:03:52.904] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:8:0)
[05:03:52.904] Timer tick, asking Buildbucket for the build status
[05:03:53.008] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:53.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:9:0) after 1m0s
[05:04:53.047] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:9:0)
[05:04:53.047] Timer tick, asking Buildbucket for the build status
[05:04:53.121] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:53.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:10:0) after 1m0s
[05:05:53.203] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:10:0)
[05:05:53.203] Timer tick, asking Buildbucket for the build status
[05:05:53.308] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:53.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:11:0) after 1m0s
[05:06:53.421] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:11:0)
[05:06:53.421] Timer tick, asking Buildbucket for the build status
[05:06:53.713] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:53.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:12:0) after 1m0s
[05:07:53.822] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:12:0)
[05:07:53.822] Timer tick, asking Buildbucket for the build status
[05:07:54.211] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:54.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:13:0) after 1m0s
[05:08:54.296] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:13:0)
[05:08:54.296] Timer tick, asking Buildbucket for the build status
[05:08:54.480] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:54.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:14:0) after 1m0s
[05:09:54.516] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:14:0)
[05:09:54.516] Timer tick, asking Buildbucket for the build status
[05:09:54.991] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:54.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:15:0) after 1m0s
[05:10:55.205] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:15:0)
[05:10:55.205] Timer tick, asking Buildbucket for the build status
[05:10:55.319] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:55.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:16:0) after 1m0s
[05:11:55.439] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:16:0)
[05:11:55.439] Timer tick, asking Buildbucket for the build status
[05:11:55.509] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:55.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:17:0) after 1m0s
[05:12:55.546] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:17:0)
[05:12:55.546] Timer tick, asking Buildbucket for the build status
[05:12:56.267] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:56.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:18:0) after 1m0s
[05:13:56.370] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:18:0)
[05:13:56.370] Timer tick, asking Buildbucket for the build status
[05:13:58.298] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:58.298] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:19:0) after 1m0s
[05:14:58.323] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:19:0)
[05:14:58.323] Timer tick, asking Buildbucket for the build status
[05:14:58.378] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:58.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:20:0) after 1m0s
[05:15:58.422] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:20:0)
[05:15:58.422] Timer tick, asking Buildbucket for the build status
[05:15:58.493] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:58.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:21:0) after 1m0s
[05:16:58.503] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:21:0)
[05:16:58.503] Timer tick, asking Buildbucket for the build status
[05:16:58.684] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:58.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:22:0) after 1m0s
[05:17:58.764] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:22:0)
[05:17:58.764] Timer tick, asking Buildbucket for the build status
[05:17:58.849] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:58.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:23:0) after 1m0s
[05:18:58.939] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:23:0)
[05:18:58.939] Timer tick, asking Buildbucket for the build status
[05:18:59.250] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:59.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:24:0) after 1m0s
[05:19:59.317] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:24:0)
[05:19:59.317] Timer tick, asking Buildbucket for the build status
[05:19:59.541] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:59.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:25:0) after 1m0s
[05:20:59.569] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:25:0)
[05:20:59.569] Timer tick, asking Buildbucket for the build status
[05:20:59.898] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:59.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:26:0) after 1m0s
[05:22:00.039] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:26:0)
[05:22:00.039] Timer tick, asking Buildbucket for the build status
[05:22:00.332] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:00.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:27:0) after 1m0s
[05:23:00.411] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:27:0)
[05:23:00.411] Timer tick, asking Buildbucket for the build status
[05:23:00.483] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:00.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:28:0) after 1m0s
[05:24:01.490] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:28:0)
[05:24:01.490] Timer tick, asking Buildbucket for the build status
[05:24:01.590] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:01.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:29:0) after 1m0s
[05:25:02.005] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:29:0)
[05:25:02.005] Timer tick, asking Buildbucket for the build status
[05:25:02.217] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:02.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:30:0) after 1m0s
[05:26:02.302] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:30:0)
[05:26:02.302] Timer tick, asking Buildbucket for the build status
[05:26:02.395] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:02.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:31:0) after 1m0s
[05:27:02.460] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:31:0)
[05:27:02.460] Timer tick, asking Buildbucket for the build status
[05:27:02.640] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:02.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:32:0) after 1m0s
[05:28:02.670] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:32:0)
[05:28:02.670] Timer tick, asking Buildbucket for the build status
[05:28:02.772] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:02.772] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:33:0) after 1m0s
[05:29:02.804] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:33:0)
[05:29:02.804] Timer tick, asking Buildbucket for the build status
[05:29:03.176] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:03.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:34:0) after 1m0s
[05:30:03.276] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:34:0)
[05:30:03.276] Timer tick, asking Buildbucket for the build status
[05:30:03.427] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:03.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:35:0) after 1m0s
[05:31:03.760] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:35:0)
[05:31:03.760] Timer tick, asking Buildbucket for the build status
[05:31:04.098] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:04.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:36:0) after 1m0s
[05:32:04.144] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:36:0)
[05:32:04.144] Timer tick, asking Buildbucket for the build status
[05:32:04.292] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:04.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:37:0) after 1m0s
[05:33:04.363] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:37:0)
[05:33:04.364] Timer tick, asking Buildbucket for the build status
[05:33:04.536] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:04.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:38:0) after 1m0s
[05:34:04.567] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:38:0)
[05:34:04.567] Timer tick, asking Buildbucket for the build status
[05:34:04.747] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:04.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:39:0) after 1m0s
[05:35:04.957] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:39:0)
[05:35:04.957] Timer tick, asking Buildbucket for the build status
[05:35:05.054] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:05.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:40:0) after 1m0s
[05:36:05.083] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:40:0)
[05:36:05.083] Timer tick, asking Buildbucket for the build status
[05:36:05.221] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:05.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:41:0) after 1m0s
[05:37:05.227] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:41:0)
[05:37:05.227] Timer tick, asking Buildbucket for the build status
[05:37:05.277] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:05.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:42:0) after 1m0s
[05:38:05.306] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:42:0)
[05:38:05.306] Timer tick, asking Buildbucket for the build status
[05:38:05.457] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:05.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:43:0) after 1m0s
[05:39:05.582] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:43:0)
[05:39:05.582] Timer tick, asking Buildbucket for the build status
[05:39:06.273] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:06.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:44:0) after 1m0s
[05:40:06.407] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:44:0)
[05:40:06.407] Timer tick, asking Buildbucket for the build status
[05:40:06.828] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:06.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:45:0) after 1m0s
[05:41:07.070] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:45:0)
[05:41:07.070] Timer tick, asking Buildbucket for the build status
[05:41:07.147] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:07.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:46:0) after 1m0s
[05:42:07.315] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:46:0)
[05:42:07.315] Timer tick, asking Buildbucket for the build status
[05:42:07.428] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:07.428] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:47:0) after 1m0s
[05:43:07.455] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:47:0)
[05:43:07.455] Timer tick, asking Buildbucket for the build status
[05:43:07.602] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:07.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:48:0) after 1m0s
[05:44:07.723] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:48:0)
[05:44:07.723] Timer tick, asking Buildbucket for the build status
[05:44:07.789] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:07.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:49:0) after 1m0s
[05:45:07.799] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:49:0)
[05:45:07.799] Timer tick, asking Buildbucket for the build status
[05:45:07.873] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:07.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:50:0) after 1m0s
[05:46:08.052] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:50:0)
[05:46:08.052] Timer tick, asking Buildbucket for the build status
[05:46:08.113] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:08.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:51:0) after 1m0s
[05:47:08.151] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:51:0)
[05:47:08.151] Timer tick, asking Buildbucket for the build status
[05:47:08.211] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:08.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:52:0) after 1m0s
[05:48:08.282] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:52:0)
[05:48:08.282] Timer tick, asking Buildbucket for the build status
[05:48:08.391] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:08.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:53:0) after 1m0s
[05:49:08.372] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:53:0)
[05:49:08.372] Timer tick, asking Buildbucket for the build status
[05:49:08.502] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:08.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:54:0) after 1m0s
[05:50:08.592] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:54:0)
[05:50:08.592] Timer tick, asking Buildbucket for the build status
[05:50:08.656] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:08.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:55:0) after 1m0s
[05:51:08.883] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:55:0)
[05:51:08.883] Timer tick, asking Buildbucket for the build status
[05:51:08.979] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:08.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:56:0) after 1m0s
[05:52:09.034] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:56:0)
[05:52:09.034] Timer tick, asking Buildbucket for the build status
[05:52:09.428] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:09.428] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:57:0) after 1m0s
[05:53:09.520] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:57:0)
[05:53:09.520] Timer tick, asking Buildbucket for the build status
[05:53:09.711] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:09.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:58:0) after 1m0s
[05:54:09.877] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:58:0)
[05:54:09.877] Timer tick, asking Buildbucket for the build status
[05:54:10.256] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:10.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:59:0) after 1m0s
[05:55:10.239] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:59:0)
[05:55:10.239] Timer tick, asking Buildbucket for the build status
[05:55:10.301] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:10.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:60:0) after 1m0s
[05:56:10.348] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:60:0)
[05:56:10.348] Timer tick, asking Buildbucket for the build status
[05:56:10.436] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:10.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:61:0) after 1m0s
[05:57:10.491] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:61:0)
[05:57:10.491] Timer tick, asking Buildbucket for the build status
[05:57:10.600] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:10.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:62:0) after 1m0s
[05:58:10.798] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:62:0)
[05:58:10.798] Timer tick, asking Buildbucket for the build status
[05:58:10.997] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:10.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:63:0) after 1m0s
[05:59:11.078] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:63:0)
[05:59:11.078] Timer tick, asking Buildbucket for the build status
[05:59:11.188] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:11.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:64:0) after 1m0s
[06:00:11.244] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:64:0)
[06:00:11.244] Timer tick, asking Buildbucket for the build status
[06:00:11.652] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:11.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:65:0) after 1m0s
[06:01:12.608] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:65:0)
[06:01:12.608] Timer tick, asking Buildbucket for the build status
[06:01:12.698] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:12.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:66:0) after 1m0s
[06:02:12.748] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:66:0)
[06:02:12.748] Timer tick, asking Buildbucket for the build status
[06:02:12.821] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:12.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:67:0) after 1m0s
[06:03:12.870] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:67:0)
[06:03:12.870] Timer tick, asking Buildbucket for the build status
[06:03:13.737] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:13.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:68:0) after 1m0s
[06:04:13.873] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:68:0)
[06:04:13.873] Timer tick, asking Buildbucket for the build status
[06:04:13.944] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:13.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:69:0) after 1m0s
[06:05:14.131] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:69:0)
[06:05:14.131] Timer tick, asking Buildbucket for the build status
[06:05:14.202] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:14.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:70:0) after 1m0s
[06:06:14.273] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:70:0)
[06:06:14.273] Timer tick, asking Buildbucket for the build status
[06:06:14.336] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:14.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:71:0) after 1m0s
[06:07:14.445] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:71:0)
[06:07:14.445] Timer tick, asking Buildbucket for the build status
[06:07:14.511] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:14.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:72:0) after 1m0s
[06:08:14.587] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:72:0)
[06:08:14.588] Timer tick, asking Buildbucket for the build status
[06:08:14.707] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:14.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:73:0) after 1m0s
[06:09:14.801] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:73:0)
[06:09:14.801] Timer tick, asking Buildbucket for the build status
[06:09:14.868] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:14.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:74:0) after 1m0s
[06:10:14.973] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:74:0)
[06:10:14.973] Timer tick, asking Buildbucket for the build status
[06:10:15.029] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:15.029] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:75:0) after 1m0s
[06:11:15.103] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:75:0)
[06:11:15.103] Timer tick, asking Buildbucket for the build status
[06:11:15.187] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:15.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:76:0) after 1m0s
[06:12:15.245] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:76:0)
[06:12:15.245] Timer tick, asking Buildbucket for the build status
[06:12:15.387] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:15.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:77:0) after 1m0s
[06:13:15.471] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:77:0)
[06:13:15.471] Timer tick, asking Buildbucket for the build status
[06:13:15.582] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:15.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:78:0) after 1m0s
[06:14:15.683] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:78:0)
[06:14:15.683] Timer tick, asking Buildbucket for the build status
[06:14:15.789] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:15.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:79:0) after 1m0s
[06:15:15.855] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:79:0)
[06:15:15.855] Timer tick, asking Buildbucket for the build status
[06:15:15.924] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:15.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:80:0) after 1m0s
[06:16:15.985] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:80:0)
[06:16:15.985] Timer tick, asking Buildbucket for the build status
[06:16:16.066] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:16.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:81:0) after 1m0s
[06:17:16.092] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:81:0)
[06:17:16.092] Timer tick, asking Buildbucket for the build status
[06:17:16.155] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:16.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:82:0) after 1m0s
[06:18:16.252] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:82:0)
[06:18:16.252] Timer tick, asking Buildbucket for the build status
[06:18:16.397] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:16.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:83:0) after 1m0s
[06:19:16.424] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:83:0)
[06:19:16.424] Timer tick, asking Buildbucket for the build status
[06:19:16.512] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:16.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:84:0) after 1m0s
[06:20:16.625] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:84:0)
[06:20:16.625] Timer tick, asking Buildbucket for the build status
[06:20:16.923] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:16.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:85:0) after 1m0s
[06:21:17.007] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:85:0)
[06:21:17.007] Timer tick, asking Buildbucket for the build status
[06:21:17.086] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:17.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:86:0) after 1m0s
[06:22:17.206] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:86:0)
[06:22:17.206] Timer tick, asking Buildbucket for the build status
[06:22:17.288] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:17.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:87:0) after 1m0s
[06:23:17.356] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:87:0)
[06:23:17.356] Timer tick, asking Buildbucket for the build status
[06:23:17.542] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:17.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:88:0) after 1m0s
[06:24:17.611] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:88:0)
[06:24:17.611] Timer tick, asking Buildbucket for the build status
[06:24:17.935] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:17.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:89:0) after 1m0s
[06:25:17.991] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:89:0)
[06:25:17.991] Timer tick, asking Buildbucket for the build status
[06:25:18.064] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:18.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:90:0) after 1m0s
[06:26:18.091] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:90:0)
[06:26:18.091] Timer tick, asking Buildbucket for the build status
[06:26:18.346] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:18.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:91:0) after 1m0s
[06:27:18.372] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:91:0)
[06:27:18.372] Timer tick, asking Buildbucket for the build status
[06:27:18.444] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:18.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:92:0) after 1m0s
[06:28:18.520] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:92:0)
[06:28:18.520] Timer tick, asking Buildbucket for the build status
[06:28:18.768] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:18.768] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:93:0) after 1m0s
[06:29:18.884] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:93:0)
[06:29:18.884] Timer tick, asking Buildbucket for the build status
[06:29:18.956] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:18.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:94:0) after 1m0s
[06:30:19.024] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:94:0)
[06:30:19.024] Timer tick, asking Buildbucket for the build status
[06:30:19.083] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:19.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:95:0) after 1m0s
[06:31:19.123] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:95:0)
[06:31:19.123] Timer tick, asking Buildbucket for the build status
[06:31:19.295] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:19.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:96:0) after 1m0s
[06:32:19.358] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:96:0)
[06:32:19.358] Timer tick, asking Buildbucket for the build status
[06:32:19.433] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:19.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:97:0) after 1m0s
[06:33:19.487] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:97:0)
[06:33:19.487] Timer tick, asking Buildbucket for the build status
[06:33:19.602] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:19.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:98:0) after 1m0s
[06:34:19.675] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:98:0)
[06:34:19.675] Timer tick, asking Buildbucket for the build status
[06:34:19.835] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:19.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:99:0) after 1m0s
[06:35:19.922] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:99:0)
[06:35:19.922] Timer tick, asking Buildbucket for the build status
[06:35:19.998] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:19.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:100:0) after 1m0s
[06:36:20.076] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:100:0)
[06:36:20.076] Timer tick, asking Buildbucket for the build status
[06:36:20.216] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:20.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:101:0) after 1m0s
[06:37:20.367] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:101:0)
[06:37:20.367] Timer tick, asking Buildbucket for the build status
[06:37:20.537] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:20.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:102:0) after 1m0s
[06:38:20.575] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:102:0)
[06:38:20.575] Timer tick, asking Buildbucket for the build status
[06:38:20.671] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:20.671] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:103:0) after 1m0s
[06:39:20.775] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:103:0)
[06:39:20.775] Timer tick, asking Buildbucket for the build status
[06:39:20.857] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:20.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:104:0) after 1m0s
[06:40:20.969] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:104:0)
[06:40:20.969] Timer tick, asking Buildbucket for the build status
[06:40:21.034] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:21.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:105:0) after 1m0s
[06:41:21.112] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:105:0)
[06:41:21.112] Timer tick, asking Buildbucket for the build status
[06:41:21.182] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:21.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:106:0) after 1m0s
[06:42:21.227] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:106:0)
[06:42:21.227] Timer tick, asking Buildbucket for the build status
[06:42:21.399] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:21.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:107:0) after 1m0s
[06:43:21.498] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:107:0)
[06:43:21.498] Timer tick, asking Buildbucket for the build status
[06:43:21.982] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:21.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:108:0) after 1m0s
[06:44:22.010] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:108:0)
[06:44:22.010] Timer tick, asking Buildbucket for the build status
[06:44:22.084] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:22.084] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:109:0) after 1m0s
[06:45:22.326] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:109:0)
[06:45:22.326] Timer tick, asking Buildbucket for the build status
[06:45:22.639] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:22.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:110:0) after 1m0s
[06:46:22.683] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:110:0)
[06:46:22.683] Timer tick, asking Buildbucket for the build status
[06:46:22.885] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:22.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:111:0) after 1m0s
[06:47:23.077] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:111:0)
[06:47:23.077] Timer tick, asking Buildbucket for the build status
[06:47:23.157] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:23.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:112:0) after 1m0s
[06:48:23.282] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:112:0)
[06:48:23.283] Timer tick, asking Buildbucket for the build status
[06:48:23.531] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:23.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:113:0) after 1m0s
[06:49:23.635] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:113:0)
[06:49:23.635] Timer tick, asking Buildbucket for the build status
[06:49:23.707] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:23.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:114:0) after 1m0s
[06:50:23.836] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:114:0)
[06:50:23.836] Timer tick, asking Buildbucket for the build status
[06:50:24.127] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:24.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:115:0) after 1m0s
[06:51:24.164] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:115:0)
[06:51:24.164] Timer tick, asking Buildbucket for the build status
[06:51:24.540] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:24.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:116:0) after 1m0s
[06:52:24.603] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:116:0)
[06:52:24.603] Timer tick, asking Buildbucket for the build status
[06:52:24.658] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:24.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:117:0) after 1m0s
[06:53:27.839] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:117:0)
[06:53:27.839] Timer tick, asking Buildbucket for the build status
[06:53:28.464] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:28.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:118:0) after 1m0s
[06:54:28.517] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:118:0)
[06:54:28.517] Timer tick, asking Buildbucket for the build status
[06:54:28.580] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:28.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:119:0) after 1m0s
[06:55:28.767] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:119:0)
[06:55:28.767] Timer tick, asking Buildbucket for the build status
[06:55:29.309] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:29.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:120:0) after 1m0s
[06:56:29.386] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:120:0)
[06:56:29.386] Timer tick, asking Buildbucket for the build status
[06:56:29.513] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:29.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:121:0) after 1m0s
[06:57:29.686] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:121:0)
[06:57:29.686] Timer tick, asking Buildbucket for the build status
[06:57:29.758] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:29.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:122:0) after 1m0s
[06:58:29.830] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:122:0)
[06:58:29.830] Timer tick, asking Buildbucket for the build status
[06:58:30.377] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:30.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:123:0) after 1m0s
[06:59:30.574] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:123:0)
[06:59:30.574] Timer tick, asking Buildbucket for the build status
[06:59:30.665] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:30.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:124:0) after 1m0s
[07:00:30.782] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:124:0)
[07:00:30.782] Timer tick, asking Buildbucket for the build status
[07:00:30.856] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:30.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:125:0) after 1m0s
[07:01:30.894] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:125:0)
[07:01:30.894] Timer tick, asking Buildbucket for the build status
[07:01:31.097] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:31.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:126:0) after 1m0s
[07:02:31.190] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:126:0)
[07:02:31.190] Timer tick, asking Buildbucket for the build status
[07:02:31.294] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:31.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:127:0) after 1m0s
[07:03:31.398] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:127:0)
[07:03:31.399] Timer tick, asking Buildbucket for the build status
[07:03:31.594] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:31.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:128:0) after 1m0s
[07:04:32.031] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:128:0)
[07:04:32.031] Timer tick, asking Buildbucket for the build status
[07:04:32.175] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:32.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:129:0) after 1m0s
[07:05:32.260] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:129:0)
[07:05:32.260] Timer tick, asking Buildbucket for the build status
[07:05:32.339] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:32.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:130:0) after 1m0s
[07:06:32.427] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:130:0)
[07:06:32.427] Timer tick, asking Buildbucket for the build status
[07:06:32.557] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:32.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:131:0) after 1m0s
[07:07:32.645] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:131:0)
[07:07:32.645] Timer tick, asking Buildbucket for the build status
[07:07:32.722] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:32.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:132:0) after 1m0s
[07:08:32.772] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:132:0)
[07:08:32.772] Timer tick, asking Buildbucket for the build status
[07:08:32.884] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:32.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:133:0) after 1m0s
[07:09:32.989] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:133:0)
[07:09:32.989] Timer tick, asking Buildbucket for the build status
[07:09:33.073] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:33.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:134:0) after 1m0s
[07:10:33.156] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:134:0)
[07:10:33.156] Timer tick, asking Buildbucket for the build status
[07:10:33.388] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:33.389] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:135:0) after 1m0s
[07:11:33.449] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:135:0)
[07:11:33.449] Timer tick, asking Buildbucket for the build status
[07:11:33.754] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:33.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:136:0) after 1m0s
[07:12:33.882] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:136:0)
[07:12:33.882] Timer tick, asking Buildbucket for the build status
[07:12:33.944] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:33.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:137:0) after 1m0s
[07:13:33.973] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:137:0)
[07:13:33.973] Timer tick, asking Buildbucket for the build status
[07:13:34.074] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:34.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:138:0) after 1m0s
[07:14:34.123] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:138:0)
[07:14:34.123] Timer tick, asking Buildbucket for the build status
[07:14:34.236] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:34.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:139:0) after 1m0s
[07:15:34.265] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:139:0)
[07:15:34.265] Timer tick, asking Buildbucket for the build status
[07:15:34.353] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:34.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:140:0) after 1m0s
[07:16:34.388] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:140:0)
[07:16:34.388] Timer tick, asking Buildbucket for the build status
[07:16:34.711] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:34.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:141:0) after 1m0s
[07:17:34.821] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:141:0)
[07:17:34.822] Timer tick, asking Buildbucket for the build status
[07:17:34.917] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:34.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:142:0) after 1m0s
[07:18:34.988] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:142:0)
[07:18:34.988] Timer tick, asking Buildbucket for the build status
[07:18:35.267] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:35.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:143:0) after 1m0s
[07:19:35.327] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:143:0)
[07:19:35.327] Timer tick, asking Buildbucket for the build status
[07:19:35.508] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:35.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:144:0) after 1m0s
[07:20:35.573] Handling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:144:0)
[07:20:35.573] Timer tick, asking Buildbucket for the build status
[07:20:35.758] Build 8864136327387083136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:35.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9029566389463761824:145:0) after 1m0s
[07:20:50.682] Received PubSub notification, asking Buildbucket for the build status
[07:20:50.819] Build 8864136327387083136: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:20:50.819] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604906449917781",
"created_by": "project:chromium",
"created_ts": "1604897869655227",
"id": "8864136327387083136",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5f273f29447579cad34cbfee84e78b2e7d9187be\"}}",
"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\": \"5f273f29447579cad34cbfee84e78b2e7d9187be\", \"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-1-xnxg\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 994, \"got_angle_revision\": \"d4439824c55428a699aa543a2f397d06a00841b6\", \"got_dawn_revision\": \"3e17e33724b4d0b4c4fc1985340882e1f543e3a0\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"5f273f29447579cad34cbfee84e78b2e7d9187be\", \"got_revision_cp\": \"refs/heads/master@{#825250}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"4ed9d3498dcffa987acba1a8007ff8dec336f263\", \"got_v8_revision\": \"4075f123295bb39d50e472b7af1fc04d4f4fe228\", \"got_v8_revision_cp\": \"refs/heads/8.8.250@{#1}\", \"got_webrtc_revision\": \"9c99b7964fabb16a022293ffbd2804f9b46551f6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32565}\", \"mirrored_builders\": [\"tryserver.chromium:win-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5f273f29447579cad34cbfee84e78b2e7d9187be\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-1-xnxg\"], \"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\": [\"5626-39642e9\"], \"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": "1604897908696965",
"status": "COMPLETED",
"status_changed_ts": "1604906450384508",
"tags": [
"build_address:luci.chromium.ci/win-official/994",
"builder:win-official",
"buildset:commit/git/5f273f29447579cad34cbfee84e78b2e7d9187be",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5f273f29447579cad34cbfee84e78b2e7d9187be",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029566389463761824",
"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/8864136327387083136/+/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:4fc42fcf1735a310",
"user_agent:luci-scheduler"
],
"updated_ts": "1604906450405111",
"url": "https://ci.chromium.org/b/8864136327387083136",
"utcnow_ts": "1604906450806457"
}
}
[07:20:50.819] Invocation finished in 2h23m2.527892582s with status SUCCEEDED