[14:20:33.175] New invocation is queued and will start shortly
[14:20:34.557] Starting the invocation (attempt 1)
[14:20:34.592] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:20:34.593] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:20:34.593] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9034151429719626416",
"parameters_json": "{\"builder_name\":\"win32-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"7a5a696b93a94b3283be1d13b1346703fd0aa4ba\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win32-official",
"scheduler_invocation_id:9034151429719626416",
"scheduler_job_id:chromium/win32-official",
"user_agent:luci-scheduler",
"buildset:commit/git/7a5a696b93a94b3283be1d13b1346703fd0aa4ba",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7a5a696b93a94b3283be1d13b1346703fd0aa4ba",
"gitiles_ref:refs/heads/master"
]
}
[14:20:35.563] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1600525234682614",
"id": "8868721367476582912",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7a5a696b93a94b3283be1d13b1346703fd0aa4ba\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1600525235222604",
"tags": [
"build_address:luci.chromium.ci/win32-official/565",
"builder:win32-official",
"buildset:commit/git/7a5a696b93a94b3283be1d13b1346703fd0aa4ba",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7a5a696b93a94b3283be1d13b1346703fd0aa4ba",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9034151429719626416",
"scheduler_job_id:chromium/win32-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8868721367476582912/+/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": "1600525235222744",
"url": "https://ci.chromium.org/b/8868721367476582912",
"utcnow_ts": "1600525235548878"
}
}
[14:20:35.563] Task URL: https://ci.chromium.org/b/8868721367476582912
[14:20:35.563] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:2:0) after 1m0s
[14:20:38.086] Received PubSub notification, asking Buildbucket for the build status
[14:20:38.295] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:35.688] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:2:0)
[14:21:35.688] Timer tick, asking Buildbucket for the build status
[14:21:36.244] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:36.244] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:4:0) after 1m0s
[14:22:36.285] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:4:0)
[14:22:36.286] Timer tick, asking Buildbucket for the build status
[14:22:36.412] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:36.412] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:5:0) after 1m0s
[14:23:36.472] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:5:0)
[14:23:36.472] Timer tick, asking Buildbucket for the build status
[14:23:36.532] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:36.532] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:6:0) after 1m0s
[14:24:36.561] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:6:0)
[14:24:36.561] Timer tick, asking Buildbucket for the build status
[14:24:36.639] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:36.639] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:7:0) after 1m0s
[14:25:36.678] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:7:0)
[14:25:36.678] Timer tick, asking Buildbucket for the build status
[14:25:37.091] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:37.091] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:8:0) after 1m0s
[14:26:37.233] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:8:0)
[14:26:37.233] Timer tick, asking Buildbucket for the build status
[14:26:37.291] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:37.291] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:9:0) after 1m0s
[14:27:37.271] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:9:0)
[14:27:37.271] Timer tick, asking Buildbucket for the build status
[14:27:37.349] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:37.349] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:10:0) after 1m0s
[14:28:37.353] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:10:0)
[14:28:37.353] Timer tick, asking Buildbucket for the build status
[14:28:37.638] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:37.638] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:11:0) after 1m0s
[14:29:37.722] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:11:0)
[14:29:37.722] Timer tick, asking Buildbucket for the build status
[14:29:37.907] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:37.907] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:12:0) after 1m0s
[14:30:37.941] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:12:0)
[14:30:37.941] Timer tick, asking Buildbucket for the build status
[14:30:38.100] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:38.100] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:13:0) after 1m0s
[14:31:38.150] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:13:0)
[14:31:38.150] Timer tick, asking Buildbucket for the build status
[14:31:38.635] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:38.635] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:14:0) after 1m0s
[14:32:38.632] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:14:0)
[14:32:38.632] Timer tick, asking Buildbucket for the build status
[14:32:38.800] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:38.800] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:15:0) after 1m0s
[14:33:38.814] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:15:0)
[14:33:38.814] Timer tick, asking Buildbucket for the build status
[14:33:38.916] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:38.916] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:16:0) after 1m0s
[14:34:38.934] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:16:0)
[14:34:38.934] Timer tick, asking Buildbucket for the build status
[14:34:38.997] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:38.997] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:17:0) after 1m0s
[14:35:39.029] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:17:0)
[14:35:39.029] Timer tick, asking Buildbucket for the build status
[14:35:39.557] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:39.557] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:18:0) after 1m0s
[14:36:39.643] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:18:0)
[14:36:39.643] Timer tick, asking Buildbucket for the build status
[14:36:39.707] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:39.707] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:19:0) after 1m0s
[14:37:39.685] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:19:0)
[14:37:39.685] Timer tick, asking Buildbucket for the build status
[14:37:39.790] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:39.790] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:20:0) after 1m0s
[14:38:39.885] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:20:0)
[14:38:39.885] Timer tick, asking Buildbucket for the build status
[14:38:39.953] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:39.953] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:21:0) after 1m0s
[14:39:39.986] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:21:0)
[14:39:39.986] Timer tick, asking Buildbucket for the build status
[14:39:40.306] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:40.306] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:22:0) after 1m0s
[14:40:40.355] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:22:0)
[14:40:40.355] Timer tick, asking Buildbucket for the build status
[14:40:40.468] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:40.468] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:23:0) after 1m0s
[14:41:40.627] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:23:0)
[14:41:40.627] Timer tick, asking Buildbucket for the build status
[14:41:40.891] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:40.891] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:24:0) after 1m0s
[14:42:41.001] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:24:0)
[14:42:41.001] Timer tick, asking Buildbucket for the build status
[14:42:41.106] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:41.106] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:25:0) after 1m0s
[14:43:41.212] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:25:0)
[14:43:41.212] Timer tick, asking Buildbucket for the build status
[14:43:41.300] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:41.300] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:26:0) after 1m0s
[14:44:41.416] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:26:0)
[14:44:41.416] Timer tick, asking Buildbucket for the build status
[14:44:41.493] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:41.493] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:27:0) after 1m0s
[14:45:41.551] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:27:0)
[14:45:41.551] Timer tick, asking Buildbucket for the build status
[14:45:42.196] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:42.196] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:28:0) after 1m0s
[14:46:42.298] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:28:0)
[14:46:42.298] Timer tick, asking Buildbucket for the build status
[14:46:42.421] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:42.421] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:29:0) after 1m0s
[14:47:42.561] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:29:0)
[14:47:42.561] Timer tick, asking Buildbucket for the build status
[14:47:42.879] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:42.879] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:30:0) after 1m0s
[14:48:43.026] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:30:0)
[14:48:43.026] Timer tick, asking Buildbucket for the build status
[14:48:43.128] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:43.128] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:31:0) after 1m0s
[14:49:43.196] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:31:0)
[14:49:43.196] Timer tick, asking Buildbucket for the build status
[14:49:43.274] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:43.274] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:32:0) after 1m0s
[14:50:43.291] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:32:0)
[14:50:43.291] Timer tick, asking Buildbucket for the build status
[14:50:43.510] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:43.510] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:33:0) after 1m0s
[14:51:43.547] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:33:0)
[14:51:43.547] Timer tick, asking Buildbucket for the build status
[14:51:44.034] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:44.034] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:34:0) after 1m0s
[14:52:44.061] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:34:0)
[14:52:44.061] Timer tick, asking Buildbucket for the build status
[14:52:44.122] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:44.122] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:35:0) after 1m0s
[14:53:44.161] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:35:0)
[14:53:44.161] Timer tick, asking Buildbucket for the build status
[14:53:44.246] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:44.246] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:36:0) after 1m0s
[14:54:44.388] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:36:0)
[14:54:44.388] Timer tick, asking Buildbucket for the build status
[14:54:44.469] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:44.469] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:37:0) after 1m0s
[14:55:44.518] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:37:0)
[14:55:44.518] Timer tick, asking Buildbucket for the build status
[14:55:44.616] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:44.616] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:38:0) after 1m0s
[14:56:44.646] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:38:0)
[14:56:44.646] Timer tick, asking Buildbucket for the build status
[14:56:44.839] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:44.839] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:39:0) after 1m0s
[14:57:47.947] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:39:0)
[14:57:47.947] Timer tick, asking Buildbucket for the build status
[14:57:48.013] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:48.013] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:40:0) after 1m0s
[14:58:48.021] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:40:0)
[14:58:48.021] Timer tick, asking Buildbucket for the build status
[14:58:48.311] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:48.311] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:41:0) after 1m0s
[14:59:48.398] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:41:0)
[14:59:48.398] Timer tick, asking Buildbucket for the build status
[14:59:48.661] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:48.662] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:42:0) after 1m0s
[15:00:48.765] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:42:0)
[15:00:48.765] Timer tick, asking Buildbucket for the build status
[15:00:48.875] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:48.875] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:43:0) after 1m0s
[15:01:48.983] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:43:0)
[15:01:48.983] Timer tick, asking Buildbucket for the build status
[15:01:49.334] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:49.334] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:44:0) after 1m0s
[15:02:49.363] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:44:0)
[15:02:49.363] Timer tick, asking Buildbucket for the build status
[15:02:49.522] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:49.522] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:45:0) after 1m0s
[15:03:49.624] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:45:0)
[15:03:49.624] Timer tick, asking Buildbucket for the build status
[15:03:49.685] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:49.685] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:46:0) after 1m0s
[15:04:49.709] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:46:0)
[15:04:49.709] Timer tick, asking Buildbucket for the build status
[15:04:49.778] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:49.778] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:47:0) after 1m0s
[15:05:49.899] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:47:0)
[15:05:49.899] Timer tick, asking Buildbucket for the build status
[15:05:50.056] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:50.056] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:48:0) after 1m0s
[15:06:50.220] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:48:0)
[15:06:50.220] Timer tick, asking Buildbucket for the build status
[15:06:50.313] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:50.313] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:49:0) after 1m0s
[15:07:50.353] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:49:0)
[15:07:50.353] Timer tick, asking Buildbucket for the build status
[15:07:50.448] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:50.448] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:50:0) after 1m0s
[15:08:50.457] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:50:0)
[15:08:50.457] Timer tick, asking Buildbucket for the build status
[15:08:50.763] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:50.763] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:51:0) after 1m0s
[15:09:50.897] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:51:0)
[15:09:50.897] Timer tick, asking Buildbucket for the build status
[15:09:50.969] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:50.969] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:52:0) after 1m0s
[15:10:51.068] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:52:0)
[15:10:51.068] Timer tick, asking Buildbucket for the build status
[15:10:51.425] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:51.426] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:53:0) after 1m0s
[15:11:51.523] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:53:0)
[15:11:51.523] Timer tick, asking Buildbucket for the build status
[15:11:51.821] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:51.821] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:54:0) after 1m0s
[15:12:52.572] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:54:0)
[15:12:52.572] Timer tick, asking Buildbucket for the build status
[15:12:52.722] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:52.722] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:55:0) after 1m0s
[15:13:52.985] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:55:0)
[15:13:52.986] Timer tick, asking Buildbucket for the build status
[15:13:53.203] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:53.203] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:56:0) after 1m0s
[15:14:53.412] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:56:0)
[15:14:53.412] Timer tick, asking Buildbucket for the build status
[15:14:53.465] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:53.465] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:57:0) after 1m0s
[15:15:53.628] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:57:0)
[15:15:53.628] Timer tick, asking Buildbucket for the build status
[15:15:53.872] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:53.872] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:58:0) after 1m0s
[15:16:53.941] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:58:0)
[15:16:53.941] Timer tick, asking Buildbucket for the build status
[15:16:53.996] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:53.996] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:59:0) after 1m0s
[15:17:54.158] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:59:0)
[15:17:54.158] Timer tick, asking Buildbucket for the build status
[15:17:54.401] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:54.401] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:60:0) after 1m0s
[15:18:54.598] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:60:0)
[15:18:54.598] Timer tick, asking Buildbucket for the build status
[15:18:54.671] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:54.671] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:61:0) after 1m0s
[15:19:54.838] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:61:0)
[15:19:54.838] Timer tick, asking Buildbucket for the build status
[15:19:54.927] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:54.927] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:62:0) after 1m0s
[15:20:55.065] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:62:0)
[15:20:55.065] Timer tick, asking Buildbucket for the build status
[15:20:55.180] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:55.180] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:63:0) after 1m0s
[15:21:55.334] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:63:0)
[15:21:55.334] Timer tick, asking Buildbucket for the build status
[15:21:55.811] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:55.811] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:64:0) after 1m0s
[15:22:55.798] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:64:0)
[15:22:55.798] Timer tick, asking Buildbucket for the build status
[15:22:55.857] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:55.857] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:65:0) after 1m0s
[15:23:55.971] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:65:0)
[15:23:55.971] Timer tick, asking Buildbucket for the build status
[15:23:56.031] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:56.031] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:66:0) after 1m0s
[15:24:56.095] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:66:0)
[15:24:56.095] Timer tick, asking Buildbucket for the build status
[15:24:56.170] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:56.170] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:67:0) after 1m0s
[15:25:56.198] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:67:0)
[15:25:56.198] Timer tick, asking Buildbucket for the build status
[15:25:56.493] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:56.493] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:68:0) after 1m0s
[15:26:56.470] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:68:0)
[15:26:56.470] Timer tick, asking Buildbucket for the build status
[15:26:56.539] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:56.539] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:69:0) after 1m0s
[15:27:56.566] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:69:0)
[15:27:56.566] Timer tick, asking Buildbucket for the build status
[15:27:57.292] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:57.292] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:70:0) after 1m0s
[15:28:57.361] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:70:0)
[15:28:57.361] Timer tick, asking Buildbucket for the build status
[15:28:57.418] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:57.418] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:71:0) after 1m0s
[15:29:57.605] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:71:0)
[15:29:57.605] Timer tick, asking Buildbucket for the build status
[15:29:57.792] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:57.792] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:72:0) after 1m0s
[15:30:57.871] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:72:0)
[15:30:57.871] Timer tick, asking Buildbucket for the build status
[15:30:57.982] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:57.982] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:73:0) after 1m0s
[15:31:58.014] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:73:0)
[15:31:58.014] Timer tick, asking Buildbucket for the build status
[15:31:58.091] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:58.091] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:74:0) after 1m0s
[15:32:58.162] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:74:0)
[15:32:58.162] Timer tick, asking Buildbucket for the build status
[15:32:58.249] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:58.249] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:75:0) after 1m0s
[15:33:58.272] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:75:0)
[15:33:58.272] Timer tick, asking Buildbucket for the build status
[15:33:58.425] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:58.425] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:76:0) after 1m0s
[15:34:58.455] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:76:0)
[15:34:58.455] Timer tick, asking Buildbucket for the build status
[15:34:58.532] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:58.532] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:77:0) after 1m0s
[15:35:58.622] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:77:0)
[15:35:58.622] Timer tick, asking Buildbucket for the build status
[15:35:58.722] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:58.722] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:78:0) after 1m0s
[15:36:58.756] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:78:0)
[15:36:58.756] Timer tick, asking Buildbucket for the build status
[15:36:58.854] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:58.854] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:79:0) after 1m0s
[15:37:58.885] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:79:0)
[15:37:58.885] Timer tick, asking Buildbucket for the build status
[15:37:59.043] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:59.043] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:80:0) after 1m0s
[15:38:59.113] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:80:0)
[15:38:59.113] Timer tick, asking Buildbucket for the build status
[15:38:59.188] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:59.188] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:81:0) after 1m0s
[15:39:59.207] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:81:0)
[15:39:59.207] Timer tick, asking Buildbucket for the build status
[15:39:59.265] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:59.265] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:82:0) after 1m0s
[15:40:59.323] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:82:0)
[15:40:59.323] Timer tick, asking Buildbucket for the build status
[15:40:59.515] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:59.515] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:83:0) after 1m0s
[15:41:59.607] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:83:0)
[15:41:59.607] Timer tick, asking Buildbucket for the build status
[15:41:59.780] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:59.780] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:84:0) after 1m0s
[15:42:59.804] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:84:0)
[15:42:59.804] Timer tick, asking Buildbucket for the build status
[15:42:59.864] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:59.864] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:85:0) after 1m0s
[15:43:59.886] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:85:0)
[15:43:59.886] Timer tick, asking Buildbucket for the build status
[15:44:00.186] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:00.186] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:86:0) after 1m0s
[15:45:00.190] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:86:0)
[15:45:00.190] Timer tick, asking Buildbucket for the build status
[15:45:00.625] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:00.625] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:87:0) after 1m0s
[15:46:00.707] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:87:0)
[15:46:00.707] Timer tick, asking Buildbucket for the build status
[15:46:01.008] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:01.008] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:88:0) after 1m0s
[15:47:02.663] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:88:0)
[15:47:02.663] Timer tick, asking Buildbucket for the build status
[15:47:02.727] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:02.727] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:89:0) after 1m0s
[15:48:02.876] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:89:0)
[15:48:02.876] Timer tick, asking Buildbucket for the build status
[15:48:02.944] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:02.944] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:90:0) after 1m0s
[15:49:03.069] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:90:0)
[15:49:03.069] Timer tick, asking Buildbucket for the build status
[15:49:03.135] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:03.135] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:91:0) after 1m0s
[15:50:03.271] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:91:0)
[15:50:03.271] Timer tick, asking Buildbucket for the build status
[15:50:03.438] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:03.438] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:92:0) after 1m0s
[15:51:03.503] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:92:0)
[15:51:03.503] Timer tick, asking Buildbucket for the build status
[15:51:03.612] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:03.612] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:93:0) after 1m0s
[15:52:03.639] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:93:0)
[15:52:03.639] Timer tick, asking Buildbucket for the build status
[15:52:03.706] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:03.706] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:94:0) after 1m0s
[15:53:03.841] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:94:0)
[15:53:03.841] Timer tick, asking Buildbucket for the build status
[15:53:03.923] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:03.923] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:95:0) after 1m0s
[15:54:03.955] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:95:0)
[15:54:03.955] Timer tick, asking Buildbucket for the build status
[15:54:04.075] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:04.075] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:96:0) after 1m0s
[15:55:04.263] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:96:0)
[15:55:04.263] Timer tick, asking Buildbucket for the build status
[15:55:04.448] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:04.448] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:97:0) after 1m0s
[15:56:04.517] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:97:0)
[15:56:04.517] Timer tick, asking Buildbucket for the build status
[15:56:04.637] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:04.637] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:98:0) after 1m0s
[15:57:04.723] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:98:0)
[15:57:04.723] Timer tick, asking Buildbucket for the build status
[15:57:04.817] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:04.817] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:99:0) after 1m0s
[15:58:05.103] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:99:0)
[15:58:05.103] Timer tick, asking Buildbucket for the build status
[15:58:05.275] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:05.275] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:100:0) after 1m0s
[15:59:05.436] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:100:0)
[15:59:05.436] Timer tick, asking Buildbucket for the build status
[15:59:05.513] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:05.513] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:101:0) after 1m0s
[16:00:05.896] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:101:0)
[16:00:05.896] Timer tick, asking Buildbucket for the build status
[16:00:06.008] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:06.008] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:102:0) after 1m0s
[16:01:07.138] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:102:0)
[16:01:07.138] Timer tick, asking Buildbucket for the build status
[16:01:07.239] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:07.239] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:103:0) after 1m0s
[16:02:07.376] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:103:0)
[16:02:07.376] Timer tick, asking Buildbucket for the build status
[16:02:07.463] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:07.463] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:104:0) after 1m0s
[16:03:07.523] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:104:0)
[16:03:07.523] Timer tick, asking Buildbucket for the build status
[16:03:07.576] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:07.576] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:105:0) after 1m0s
[16:04:07.583] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:105:0)
[16:04:07.583] Timer tick, asking Buildbucket for the build status
[16:04:07.898] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:07.898] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:106:0) after 1m0s
[16:05:07.917] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:106:0)
[16:05:07.917] Timer tick, asking Buildbucket for the build status
[16:05:08.026] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:08.026] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:107:0) after 1m0s
[16:06:08.048] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:107:0)
[16:06:08.048] Timer tick, asking Buildbucket for the build status
[16:06:08.770] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:08.770] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:108:0) after 1m0s
[16:07:08.792] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:108:0)
[16:07:08.792] Timer tick, asking Buildbucket for the build status
[16:07:08.852] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:08.852] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:109:0) after 1m0s
[16:08:08.900] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:109:0)
[16:08:08.900] Timer tick, asking Buildbucket for the build status
[16:08:08.976] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:08.976] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:110:0) after 1m0s
[16:09:09.035] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:110:0)
[16:09:09.035] Timer tick, asking Buildbucket for the build status
[16:09:09.286] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:09.286] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:111:0) after 1m0s
[16:10:09.372] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:111:0)
[16:10:09.372] Timer tick, asking Buildbucket for the build status
[16:10:09.440] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:09.440] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:112:0) after 1m0s
[16:11:09.538] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:112:0)
[16:11:09.538] Timer tick, asking Buildbucket for the build status
[16:11:09.829] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:09.829] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:113:0) after 1m0s
[16:12:09.921] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:113:0)
[16:12:09.921] Timer tick, asking Buildbucket for the build status
[16:12:10.388] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:10.388] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:114:0) after 1m0s
[16:13:10.506] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:114:0)
[16:13:10.506] Timer tick, asking Buildbucket for the build status
[16:13:10.838] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:10.838] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:115:0) after 1m0s
[16:14:10.868] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:115:0)
[16:14:10.868] Timer tick, asking Buildbucket for the build status
[16:14:11.242] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:11.242] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:116:0) after 1m0s
[16:15:11.298] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:116:0)
[16:15:11.298] Timer tick, asking Buildbucket for the build status
[16:15:11.372] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:11.372] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:117:0) after 1m0s
[16:16:11.421] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:117:0)
[16:16:11.421] Timer tick, asking Buildbucket for the build status
[16:16:11.932] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:11.932] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:118:0) after 1m0s
[16:17:11.990] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:118:0)
[16:17:11.990] Timer tick, asking Buildbucket for the build status
[16:17:12.141] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:12.141] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:119:0) after 1m0s
[16:18:12.184] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:119:0)
[16:18:12.184] Timer tick, asking Buildbucket for the build status
[16:18:12.472] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:12.472] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:120:0) after 1m0s
[16:19:12.669] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:120:0)
[16:19:12.669] Timer tick, asking Buildbucket for the build status
[16:19:12.745] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:12.745] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:121:0) after 1m0s
[16:20:12.830] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:121:0)
[16:20:12.830] Timer tick, asking Buildbucket for the build status
[16:20:12.894] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:12.894] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:122:0) after 1m0s
[16:21:12.954] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:122:0)
[16:21:12.955] Timer tick, asking Buildbucket for the build status
[16:21:13.073] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:13.073] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:123:0) after 1m0s
[16:22:13.137] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:123:0)
[16:22:13.137] Timer tick, asking Buildbucket for the build status
[16:22:13.203] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:13.203] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:124:0) after 1m0s
[16:23:13.292] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:124:0)
[16:23:13.292] Timer tick, asking Buildbucket for the build status
[16:23:13.368] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:13.368] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:125:0) after 1m0s
[16:24:13.409] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:125:0)
[16:24:13.409] Timer tick, asking Buildbucket for the build status
[16:24:13.492] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:13.492] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:126:0) after 1m0s
[16:25:13.611] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:126:0)
[16:25:13.611] Timer tick, asking Buildbucket for the build status
[16:25:13.688] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:13.688] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:127:0) after 1m0s
[16:26:13.805] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:127:0)
[16:26:13.805] Timer tick, asking Buildbucket for the build status
[16:26:13.983] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:13.983] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:128:0) after 1m0s
[16:27:14.009] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:128:0)
[16:27:14.009] Timer tick, asking Buildbucket for the build status
[16:27:14.115] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:14.115] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:129:0) after 1m0s
[16:28:14.170] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:129:0)
[16:28:14.170] Timer tick, asking Buildbucket for the build status
[16:28:14.318] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:14.318] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:130:0) after 1m0s
[16:29:14.424] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:130:0)
[16:29:14.424] Timer tick, asking Buildbucket for the build status
[16:29:14.527] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:14.527] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:131:0) after 1m0s
[16:30:14.561] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:131:0)
[16:30:14.561] Timer tick, asking Buildbucket for the build status
[16:30:14.644] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:14.644] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:132:0) after 1m0s
[16:31:14.703] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:132:0)
[16:31:14.703] Timer tick, asking Buildbucket for the build status
[16:31:14.993] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:14.993] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:133:0) after 1m0s
[16:32:15.033] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:133:0)
[16:32:15.033] Timer tick, asking Buildbucket for the build status
[16:32:15.390] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:15.390] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:134:0) after 1m0s
[16:33:15.423] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:134:0)
[16:33:15.423] Timer tick, asking Buildbucket for the build status
[16:33:15.540] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:15.540] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:135:0) after 1m0s
[16:34:15.539] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:135:0)
[16:34:15.539] Timer tick, asking Buildbucket for the build status
[16:34:15.627] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:15.627] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:136:0) after 1m0s
[16:35:15.664] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:136:0)
[16:35:15.664] Timer tick, asking Buildbucket for the build status
[16:35:15.920] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:15.920] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:137:0) after 1m0s
[16:36:15.950] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:137:0)
[16:36:15.950] Timer tick, asking Buildbucket for the build status
[16:36:16.095] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:16.095] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:138:0) after 1m0s
[16:37:16.199] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:138:0)
[16:37:16.199] Timer tick, asking Buildbucket for the build status
[16:37:16.267] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:16.267] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:139:0) after 1m0s
[16:38:16.512] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:139:0)
[16:38:16.512] Timer tick, asking Buildbucket for the build status
[16:38:16.611] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:16.611] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:140:0) after 1m0s
[16:39:16.646] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:140:0)
[16:39:16.646] Timer tick, asking Buildbucket for the build status
[16:39:16.709] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:16.709] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:141:0) after 1m0s
[16:40:16.766] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:141:0)
[16:40:16.766] Timer tick, asking Buildbucket for the build status
[16:40:16.827] Build 8868721367476582912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:16.828] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034151429719626416:142:0) after 1m0s
[16:40:44.411] Received PubSub notification, asking Buildbucket for the build status
[16:40:44.505] Build 8868721367476582912: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:40:44.505] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1600533643764445",
"created_by": "project:chromium",
"created_ts": "1600525234682614",
"id": "8868721367476582912",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7a5a696b93a94b3283be1d13b1346703fd0aa4ba\"}}",
"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\": \"7a5a696b93a94b3283be1d13b1346703fd0aa4ba\", \"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-4-g0d1\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win32-official\", \"buildnumber\": 565, \"got_angle_revision\": \"f6f206018caf4008d52a311fe07466ad08bef808\", \"got_dawn_revision\": \"dcd37fa4f47663d1a68c1c7fb10738b6ea0e0a4c\", \"got_nacl_revision\": \"69a0d6e8affc94187af10e0673592d1b238c6eb0\", \"got_revision\": \"7a5a696b93a94b3283be1d13b1346703fd0aa4ba\", \"got_revision_cp\": \"refs/heads/master@{#808687}\", \"got_swarming_client_revision\": \"44c13d73156581ea09b9389001e58c23a4b8d70a\", \"got_swiftshader_revision\": \"fe878dedd5addbedab4033c0cb6b19bbbe04f8e4\", \"got_v8_revision\": \"80dc1e8055bca07e8da6162bace5ee87f23faccf\", \"got_v8_revision_cp\": \"refs/heads/8.7.142@{#1}\", \"got_webrtc_revision\": \"14dbf82961c32ae9133bb672c88e146942062ada\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32143}\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7a5a696b93a94b3283be1d13b1346703fd0aa4ba\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-4-g0d1\"], \"image\": [\"chrome-win10-1703-20091600-48bae199fe7\"], \"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\": [\"5531-8ab19d9\"], \"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": "1600525237368530",
"status": "COMPLETED",
"status_changed_ts": "1600533644160244",
"tags": [
"build_address:luci.chromium.ci/win32-official/565",
"builder:win32-official",
"buildset:commit/git/7a5a696b93a94b3283be1d13b1346703fd0aa4ba",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7a5a696b93a94b3283be1d13b1346703fd0aa4ba",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9034151429719626416",
"scheduler_job_id:chromium/win32-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8868721367476582912/+/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:4ebf8e7d58fd3f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1600533644165669",
"url": "https://ci.chromium.org/b/8868721367476582912",
"utcnow_ts": "1600533644488868"
}
}
[16:40:44.505] Invocation finished in 2h20m11.345701943s with status SUCCEEDED