[16:26:24.092] New invocation is queued and will start shortly
[16:26:25.200] Starting the invocation (attempt 1)
[16:26:25.248] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:26:25.248] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:26:25.248] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9039488733029402128",
"parameters_json": "{\"builder_name\":\"win32-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"b915228be06a91db61457fe0b1a86b811761a000\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win32-official",
"scheduler_invocation_id:9039488733029402128",
"scheduler_job_id:chromium/win32-official",
"user_agent:luci-scheduler",
"buildset:commit/git/b915228be06a91db61457fe0b1a86b811761a000",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b915228be06a91db61457fe0b1a86b811761a000",
"gitiles_ref:refs/heads/master"
]
}
[16:26:26.178] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1595435185372881",
"id": "8874058671021546032",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b915228be06a91db61457fe0b1a86b811761a000\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1595435185952257",
"tags": [
"build_address:luci.chromium.ci/win32-official/141",
"builder:win32-official",
"buildset:commit/git/b915228be06a91db61457fe0b1a86b811761a000",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b915228be06a91db61457fe0b1a86b811761a000",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9039488733029402128",
"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/8874058671021546032/+/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": "1595435185952413",
"url": "https://ci.chromium.org/b/8874058671021546032",
"utcnow_ts": "1595435186168776"
}
}
[16:26:26.178] Task URL: https://ci.chromium.org/b/8874058671021546032
[16:26:26.178] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:2:0) after 1m0s
[16:26:29.473] Received PubSub notification, asking Buildbucket for the build status
[16:26:29.541] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:26.217] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:2:0)
[16:27:26.217] Timer tick, asking Buildbucket for the build status
[16:27:26.326] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:26.326] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:4:0) after 1m0s
[16:28:26.377] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:4:0)
[16:28:26.377] Timer tick, asking Buildbucket for the build status
[16:28:26.806] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:26.806] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:5:0) after 1m0s
[16:29:26.841] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:5:0)
[16:29:26.841] Timer tick, asking Buildbucket for the build status
[16:29:26.916] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:26.916] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:6:0) after 1m0s
[16:30:26.989] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:6:0)
[16:30:26.989] Timer tick, asking Buildbucket for the build status
[16:30:27.219] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:27.219] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:7:0) after 1m0s
[16:31:27.286] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:7:0)
[16:31:27.286] Timer tick, asking Buildbucket for the build status
[16:31:27.494] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:27.494] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:8:0) after 1m0s
[16:32:27.539] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:8:0)
[16:32:27.539] Timer tick, asking Buildbucket for the build status
[16:32:27.747] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:27.747] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:9:0) after 1m0s
[16:33:27.774] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:9:0)
[16:33:27.775] Timer tick, asking Buildbucket for the build status
[16:33:28.424] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:28.424] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:10:0) after 1m0s
[16:34:28.468] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:10:0)
[16:34:28.468] Timer tick, asking Buildbucket for the build status
[16:34:28.632] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:28.632] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:11:0) after 1m0s
[16:35:28.689] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:11:0)
[16:35:28.689] Timer tick, asking Buildbucket for the build status
[16:35:28.829] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:28.829] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:12:0) after 1m0s
[16:36:28.945] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:12:0)
[16:36:28.945] Timer tick, asking Buildbucket for the build status
[16:36:29.094] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:29.094] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:13:0) after 1m0s
[16:37:29.121] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:13:0)
[16:37:29.121] Timer tick, asking Buildbucket for the build status
[16:37:29.197] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:29.197] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:14:0) after 1m0s
[16:38:29.311] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:14:0)
[16:38:29.311] Timer tick, asking Buildbucket for the build status
[16:38:29.396] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:29.396] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:15:0) after 1m0s
[16:39:29.398] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:15:0)
[16:39:29.398] Timer tick, asking Buildbucket for the build status
[16:39:29.476] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:29.476] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:16:0) after 1m0s
[16:40:29.499] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:16:0)
[16:40:29.499] Timer tick, asking Buildbucket for the build status
[16:40:29.691] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:29.691] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:17:0) after 1m0s
[16:41:29.761] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:17:0)
[16:41:29.761] Timer tick, asking Buildbucket for the build status
[16:41:29.903] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:29.903] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:18:0) after 1m0s
[16:42:30.019] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:18:0)
[16:42:30.019] Timer tick, asking Buildbucket for the build status
[16:42:30.153] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:30.153] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:19:0) after 1m0s
[16:43:30.240] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:19:0)
[16:43:30.240] Timer tick, asking Buildbucket for the build status
[16:43:30.341] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:30.341] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:20:0) after 1m0s
[16:44:30.366] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:20:0)
[16:44:30.366] Timer tick, asking Buildbucket for the build status
[16:44:30.439] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:30.439] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:21:0) after 1m0s
[16:45:30.500] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:21:0)
[16:45:30.500] Timer tick, asking Buildbucket for the build status
[16:45:30.647] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:30.647] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:22:0) after 1m0s
[16:46:30.709] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:22:0)
[16:46:30.709] Timer tick, asking Buildbucket for the build status
[16:46:31.121] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:31.121] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:23:0) after 1m0s
[16:47:31.181] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:23:0)
[16:47:31.181] Timer tick, asking Buildbucket for the build status
[16:47:31.488] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:31.488] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:24:0) after 1m0s
[16:48:31.491] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:24:0)
[16:48:31.491] Timer tick, asking Buildbucket for the build status
[16:48:31.557] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:31.558] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:25:0) after 1m0s
[16:49:31.596] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:25:0)
[16:49:31.596] Timer tick, asking Buildbucket for the build status
[16:49:31.683] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:31.684] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:26:0) after 1m0s
[16:50:31.709] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:26:0)
[16:50:31.709] Timer tick, asking Buildbucket for the build status
[16:50:31.777] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:31.777] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:27:0) after 1m0s
[16:51:31.821] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:27:0)
[16:51:31.821] Timer tick, asking Buildbucket for the build status
[16:51:32.319] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:32.319] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:28:0) after 1m0s
[16:52:32.468] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:28:0)
[16:52:32.468] Timer tick, asking Buildbucket for the build status
[16:52:32.536] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:32.536] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:29:0) after 1m0s
[16:53:32.540] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:29:0)
[16:53:32.540] Timer tick, asking Buildbucket for the build status
[16:53:32.771] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:32.771] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:30:0) after 1m0s
[16:54:32.824] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:30:0)
[16:54:32.825] Timer tick, asking Buildbucket for the build status
[16:54:32.914] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:32.914] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:31:0) after 1m0s
[16:55:32.956] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:31:0)
[16:55:32.956] Timer tick, asking Buildbucket for the build status
[16:55:33.037] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:33.037] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:32:0) after 1m0s
[16:56:33.061] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:32:0)
[16:56:33.061] Timer tick, asking Buildbucket for the build status
[16:56:33.205] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:33.205] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:33:0) after 1m0s
[16:57:33.269] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:33:0)
[16:57:33.269] Timer tick, asking Buildbucket for the build status
[16:57:33.356] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:33.356] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:34:0) after 1m0s
[16:58:33.469] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:34:0)
[16:58:33.469] Timer tick, asking Buildbucket for the build status
[16:58:33.591] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:33.591] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:35:0) after 1m0s
[16:59:33.670] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:35:0)
[16:59:33.670] Timer tick, asking Buildbucket for the build status
[16:59:33.812] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:33.812] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:36:0) after 1m0s
[17:00:33.922] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:36:0)
[17:00:33.922] Timer tick, asking Buildbucket for the build status
[17:00:34.044] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:34.044] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:37:0) after 1m0s
[17:01:34.101] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:37:0)
[17:01:34.101] Timer tick, asking Buildbucket for the build status
[17:01:34.638] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:34.638] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:38:0) after 1m0s
[17:02:34.703] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:38:0)
[17:02:34.703] Timer tick, asking Buildbucket for the build status
[17:02:34.972] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:34.972] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:39:0) after 1m0s
[17:03:35.046] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:39:0)
[17:03:35.046] Timer tick, asking Buildbucket for the build status
[17:03:35.240] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:35.240] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:40:0) after 1m0s
[17:04:35.297] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:40:0)
[17:04:35.297] Timer tick, asking Buildbucket for the build status
[17:04:35.382] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:35.382] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:41:0) after 1m0s
[17:05:35.474] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:41:0)
[17:05:35.474] Timer tick, asking Buildbucket for the build status
[17:05:36.452] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:36.452] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:42:0) after 1m0s
[17:06:36.478] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:42:0)
[17:06:36.478] Timer tick, asking Buildbucket for the build status
[17:06:36.697] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:36.697] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:43:0) after 1m0s
[17:07:36.983] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:43:0)
[17:07:36.983] Timer tick, asking Buildbucket for the build status
[17:07:37.066] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:37.066] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:44:0) after 1m0s
[17:08:37.204] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:44:0)
[17:08:37.204] Timer tick, asking Buildbucket for the build status
[17:08:37.276] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:37.276] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:45:0) after 1m0s
[17:09:37.356] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:45:0)
[17:09:37.356] Timer tick, asking Buildbucket for the build status
[17:09:37.422] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:37.422] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:46:0) after 1m0s
[17:10:37.518] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:46:0)
[17:10:37.518] Timer tick, asking Buildbucket for the build status
[17:10:37.580] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:37.580] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:47:0) after 1m0s
[17:11:37.640] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:47:0)
[17:11:37.640] Timer tick, asking Buildbucket for the build status
[17:11:37.822] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:37.822] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:48:0) after 1m0s
[17:12:38.000] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:48:0)
[17:12:38.000] Timer tick, asking Buildbucket for the build status
[17:12:38.086] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:38.086] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:49:0) after 1m0s
[17:13:38.219] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:49:0)
[17:13:38.219] Timer tick, asking Buildbucket for the build status
[17:13:38.292] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:38.292] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:50:0) after 1m0s
[17:14:38.325] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:50:0)
[17:14:38.325] Timer tick, asking Buildbucket for the build status
[17:14:38.425] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:38.425] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:51:0) after 1m0s
[17:15:38.494] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:51:0)
[17:15:38.494] Timer tick, asking Buildbucket for the build status
[17:15:38.565] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:38.565] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:52:0) after 1m0s
[17:16:38.609] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:52:0)
[17:16:38.609] Timer tick, asking Buildbucket for the build status
[17:16:38.996] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:38.996] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:53:0) after 1m0s
[17:17:39.026] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:53:0)
[17:17:39.026] Timer tick, asking Buildbucket for the build status
[17:17:39.188] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:39.188] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:54:0) after 1m0s
[17:18:39.337] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:54:0)
[17:18:39.337] Timer tick, asking Buildbucket for the build status
[17:18:39.428] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:39.428] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:55:0) after 1m0s
[17:19:39.486] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:55:0)
[17:19:39.486] Timer tick, asking Buildbucket for the build status
[17:19:39.784] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:39.784] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:56:0) after 1m0s
[17:20:39.890] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:56:0)
[17:20:39.890] Timer tick, asking Buildbucket for the build status
[17:20:39.971] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:39.971] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:57:0) after 1m0s
[17:21:40.000] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:57:0)
[17:21:40.000] Timer tick, asking Buildbucket for the build status
[17:21:40.334] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:40.334] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:58:0) after 1m0s
[17:22:40.365] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:58:0)
[17:22:40.365] Timer tick, asking Buildbucket for the build status
[17:22:40.431] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:40.431] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:59:0) after 1m0s
[17:23:40.477] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:59:0)
[17:23:40.477] Timer tick, asking Buildbucket for the build status
[17:23:40.581] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:40.581] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:60:0) after 1m0s
[17:24:40.734] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:60:0)
[17:24:40.734] Timer tick, asking Buildbucket for the build status
[17:24:40.820] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:40.820] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:61:0) after 1m0s
[17:25:40.996] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:61:0)
[17:25:40.996] Timer tick, asking Buildbucket for the build status
[17:25:41.062] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:41.062] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:62:0) after 1m0s
[17:26:41.208] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:62:0)
[17:26:41.208] Timer tick, asking Buildbucket for the build status
[17:26:41.471] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:41.471] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:63:0) after 1m0s
[17:27:41.623] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:63:0)
[17:27:41.624] Timer tick, asking Buildbucket for the build status
[17:27:41.685] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:41.685] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:64:0) after 1m0s
[17:28:41.829] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:64:0)
[17:28:41.829] Timer tick, asking Buildbucket for the build status
[17:28:41.911] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:41.912] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:65:0) after 1m0s
[17:29:42.098] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:65:0)
[17:29:42.098] Timer tick, asking Buildbucket for the build status
[17:29:42.180] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:42.180] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:66:0) after 1m0s
[17:30:42.392] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:66:0)
[17:30:42.392] Timer tick, asking Buildbucket for the build status
[17:30:42.454] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:42.454] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:67:0) after 1m0s
[17:31:42.600] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:67:0)
[17:31:42.600] Timer tick, asking Buildbucket for the build status
[17:31:42.733] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:42.733] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:68:0) after 1m0s
[17:32:42.896] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:68:0)
[17:32:42.896] Timer tick, asking Buildbucket for the build status
[17:32:42.978] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:42.978] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:69:0) after 1m0s
[17:33:43.125] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:69:0)
[17:33:43.125] Timer tick, asking Buildbucket for the build status
[17:33:43.222] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:43.222] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:70:0) after 1m0s
[17:34:43.303] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:70:0)
[17:34:43.303] Timer tick, asking Buildbucket for the build status
[17:34:43.437] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:43.437] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:71:0) after 1m0s
[17:35:43.498] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:71:0)
[17:35:43.498] Timer tick, asking Buildbucket for the build status
[17:35:43.639] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:43.639] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:72:0) after 1m0s
[17:36:43.660] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:72:0)
[17:36:43.660] Timer tick, asking Buildbucket for the build status
[17:36:43.925] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:43.925] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:73:0) after 1m0s
[17:37:43.956] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:73:0)
[17:37:43.956] Timer tick, asking Buildbucket for the build status
[17:37:44.237] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:44.237] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:74:0) after 1m0s
[17:38:44.330] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:74:0)
[17:38:44.330] Timer tick, asking Buildbucket for the build status
[17:38:44.451] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:44.451] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:75:0) after 1m0s
[17:39:44.844] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:75:0)
[17:39:44.844] Timer tick, asking Buildbucket for the build status
[17:39:44.955] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:44.955] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:76:0) after 1m0s
[17:40:45.038] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:76:0)
[17:40:45.038] Timer tick, asking Buildbucket for the build status
[17:40:45.122] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:45.122] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:77:0) after 1m0s
[17:41:45.215] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:77:0)
[17:41:45.215] Timer tick, asking Buildbucket for the build status
[17:41:45.618] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:45.618] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:78:0) after 1m0s
[17:42:45.706] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:78:0)
[17:42:45.706] Timer tick, asking Buildbucket for the build status
[17:42:45.842] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:45.842] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:79:0) after 1m0s
[17:43:45.914] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:79:0)
[17:43:45.914] Timer tick, asking Buildbucket for the build status
[17:43:46.014] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:46.014] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:80:0) after 1m0s
[17:44:46.073] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:80:0)
[17:44:46.073] Timer tick, asking Buildbucket for the build status
[17:44:46.400] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:46.400] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:81:0) after 1m0s
[17:45:46.426] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:81:0)
[17:45:46.426] Timer tick, asking Buildbucket for the build status
[17:45:46.533] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:46.533] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:82:0) after 1m0s
[17:46:46.575] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:82:0)
[17:46:46.575] Timer tick, asking Buildbucket for the build status
[17:46:46.804] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:46.804] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:83:0) after 1m0s
[17:47:46.834] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:83:0)
[17:47:46.834] Timer tick, asking Buildbucket for the build status
[17:47:46.938] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:46.938] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:84:0) after 1m0s
[17:48:47.077] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:84:0)
[17:48:47.077] Timer tick, asking Buildbucket for the build status
[17:48:47.160] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:47.160] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:85:0) after 1m0s
[17:49:47.176] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:85:0)
[17:49:47.176] Timer tick, asking Buildbucket for the build status
[17:49:47.309] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:47.309] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:86:0) after 1m0s
[17:50:47.331] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:86:0)
[17:50:47.331] Timer tick, asking Buildbucket for the build status
[17:50:47.437] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:47.437] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:87:0) after 1m0s
[17:51:47.476] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:87:0)
[17:51:47.476] Timer tick, asking Buildbucket for the build status
[17:51:48.114] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:48.114] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:88:0) after 1m0s
[17:52:48.131] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:88:0)
[17:52:48.131] Timer tick, asking Buildbucket for the build status
[17:52:48.245] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:48.245] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:89:0) after 1m0s
[17:53:48.282] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:89:0)
[17:53:48.282] Timer tick, asking Buildbucket for the build status
[17:53:48.464] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:48.464] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:90:0) after 1m0s
[17:54:48.574] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:90:0)
[17:54:48.574] Timer tick, asking Buildbucket for the build status
[17:54:48.934] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:48.934] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:91:0) after 1m0s
[17:55:49.178] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:91:0)
[17:55:49.178] Timer tick, asking Buildbucket for the build status
[17:55:49.257] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:49.257] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:92:0) after 1m0s
[17:56:49.303] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:92:0)
[17:56:49.303] Timer tick, asking Buildbucket for the build status
[17:56:49.547] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:49.547] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:93:0) after 1m0s
[17:57:49.628] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:93:0)
[17:57:49.628] Timer tick, asking Buildbucket for the build status
[17:57:49.697] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:49.697] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:94:0) after 1m0s
[17:58:49.756] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:94:0)
[17:58:49.756] Timer tick, asking Buildbucket for the build status
[17:58:49.863] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:49.863] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:95:0) after 1m0s
[17:59:49.933] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:95:0)
[17:59:49.933] Timer tick, asking Buildbucket for the build status
[17:59:50.018] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:50.018] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:96:0) after 1m0s
[18:00:50.055] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:96:0)
[18:00:50.055] Timer tick, asking Buildbucket for the build status
[18:00:50.209] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:50.209] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:97:0) after 1m0s
[18:01:50.289] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:97:0)
[18:01:50.289] Timer tick, asking Buildbucket for the build status
[18:01:51.989] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:51.989] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:98:0) after 1m0s
[18:02:52.033] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:98:0)
[18:02:52.033] Timer tick, asking Buildbucket for the build status
[18:02:52.157] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:52.157] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:99:0) after 1m0s
[18:03:52.226] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:99:0)
[18:03:52.226] Timer tick, asking Buildbucket for the build status
[18:03:52.478] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:52.478] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:100:0) after 1m0s
[18:04:52.584] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:100:0)
[18:04:52.584] Timer tick, asking Buildbucket for the build status
[18:04:52.666] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:52.666] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:101:0) after 1m0s
[18:05:52.694] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:101:0)
[18:05:52.694] Timer tick, asking Buildbucket for the build status
[18:05:52.753] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:52.753] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:102:0) after 1m0s
[18:06:52.814] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:102:0)
[18:06:52.814] Timer tick, asking Buildbucket for the build status
[18:06:53.004] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:53.004] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:103:0) after 1m0s
[18:07:54.995] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:103:0)
[18:07:54.995] Timer tick, asking Buildbucket for the build status
[18:07:55.285] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:55.285] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:104:0) after 1m0s
[18:08:55.339] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:104:0)
[18:08:55.339] Timer tick, asking Buildbucket for the build status
[18:08:55.420] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:55.420] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:105:0) after 1m0s
[18:09:55.847] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:105:0)
[18:09:55.847] Timer tick, asking Buildbucket for the build status
[18:09:55.930] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:55.930] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:106:0) after 1m0s
[18:10:56.149] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:106:0)
[18:10:56.149] Timer tick, asking Buildbucket for the build status
[18:10:56.272] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:56.272] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:107:0) after 1m0s
[18:11:56.670] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:107:0)
[18:11:56.670] Timer tick, asking Buildbucket for the build status
[18:11:57.093] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:57.093] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:108:0) after 1m0s
[18:12:57.396] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:108:0)
[18:12:57.396] Timer tick, asking Buildbucket for the build status
[18:12:57.539] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:57.539] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:109:0) after 1m0s
[18:13:57.968] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:109:0)
[18:13:57.968] Timer tick, asking Buildbucket for the build status
[18:13:58.115] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:58.115] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:110:0) after 1m0s
[18:14:58.192] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:110:0)
[18:14:58.192] Timer tick, asking Buildbucket for the build status
[18:14:58.278] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:58.278] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:111:0) after 1m0s
[18:15:58.753] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:111:0)
[18:15:58.753] Timer tick, asking Buildbucket for the build status
[18:15:59.044] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:59.044] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:112:0) after 1m0s
[18:16:59.480] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:112:0)
[18:16:59.480] Timer tick, asking Buildbucket for the build status
[18:16:59.855] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:59.855] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:113:0) after 1m0s
[18:18:00.296] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:113:0)
[18:18:00.296] Timer tick, asking Buildbucket for the build status
[18:18:00.954] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:00.954] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:114:0) after 1m0s
[18:19:02.369] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:114:0)
[18:19:02.369] Timer tick, asking Buildbucket for the build status
[18:19:02.575] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:02.575] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:115:0) after 1m0s
[18:20:02.856] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:115:0)
[18:20:02.856] Timer tick, asking Buildbucket for the build status
[18:20:03.087] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:03.087] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:116:0) after 1m0s
[18:21:03.756] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:116:0)
[18:21:03.756] Timer tick, asking Buildbucket for the build status
[18:21:03.976] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:03.976] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:117:0) after 1m0s
[18:22:04.277] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:117:0)
[18:22:04.277] Timer tick, asking Buildbucket for the build status
[18:22:04.423] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:04.423] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:118:0) after 1m0s
[18:23:04.537] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:118:0)
[18:23:04.537] Timer tick, asking Buildbucket for the build status
[18:23:04.634] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:04.634] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:119:0) after 1m0s
[18:24:04.881] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:119:0)
[18:24:04.882] Timer tick, asking Buildbucket for the build status
[18:24:05.144] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:05.144] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:120:0) after 1m0s
[18:25:05.215] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:120:0)
[18:25:05.215] Timer tick, asking Buildbucket for the build status
[18:25:05.403] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:05.403] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:121:0) after 1m0s
[18:26:05.445] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:121:0)
[18:26:05.445] Timer tick, asking Buildbucket for the build status
[18:26:05.687] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:05.687] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:122:0) after 1m0s
[18:27:05.797] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:122:0)
[18:27:05.797] Timer tick, asking Buildbucket for the build status
[18:27:05.968] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:05.968] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:123:0) after 1m0s
[18:28:06.208] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:123:0)
[18:28:06.208] Timer tick, asking Buildbucket for the build status
[18:28:06.279] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:06.279] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:124:0) after 1m0s
[18:29:06.330] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:124:0)
[18:29:06.330] Timer tick, asking Buildbucket for the build status
[18:29:06.397] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:06.397] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:125:0) after 1m0s
[18:30:06.479] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:125:0)
[18:30:06.479] Timer tick, asking Buildbucket for the build status
[18:30:06.808] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:06.808] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:126:0) after 1m0s
[18:31:06.874] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:126:0)
[18:31:06.874] Timer tick, asking Buildbucket for the build status
[18:31:07.012] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:07.012] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:127:0) after 1m0s
[18:32:07.059] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:127:0)
[18:32:07.059] Timer tick, asking Buildbucket for the build status
[18:32:07.220] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:07.220] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:128:0) after 1m0s
[18:33:07.277] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:128:0)
[18:33:07.277] Timer tick, asking Buildbucket for the build status
[18:33:07.394] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:07.394] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:129:0) after 1m0s
[18:34:07.493] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:129:0)
[18:34:07.493] Timer tick, asking Buildbucket for the build status
[18:34:07.614] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:07.614] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:130:0) after 1m0s
[18:35:07.817] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:130:0)
[18:35:07.817] Timer tick, asking Buildbucket for the build status
[18:35:07.988] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:07.988] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:131:0) after 1m0s
[18:36:07.983] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:131:0)
[18:36:07.983] Timer tick, asking Buildbucket for the build status
[18:36:08.062] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:08.062] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:132:0) after 1m0s
[18:37:08.092] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:132:0)
[18:37:08.092] Timer tick, asking Buildbucket for the build status
[18:37:08.295] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:08.295] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:133:0) after 1m0s
[18:38:18.624] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:133:0)
[18:38:18.624] Timer tick, asking Buildbucket for the build status
[18:38:19.064] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:19.064] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:134:0) after 1m0s
[18:39:19.349] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:134:0)
[18:39:19.349] Timer tick, asking Buildbucket for the build status
[18:39:19.418] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:19.418] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:135:0) after 1m0s
[18:40:19.684] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:135:0)
[18:40:19.684] Timer tick, asking Buildbucket for the build status
[18:40:19.758] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:19.758] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:136:0) after 1m0s
[18:41:19.867] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:136:0)
[18:41:19.867] Timer tick, asking Buildbucket for the build status
[18:41:19.932] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:19.932] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:137:0) after 1m0s
[18:42:20.234] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:137:0)
[18:42:20.234] Timer tick, asking Buildbucket for the build status
[18:42:20.552] Build 8874058671021546032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:20.552] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9039488733029402128:138:0) after 1m0s
[18:42:51.650] Received PubSub notification, asking Buildbucket for the build status
[18:42:51.753] Build 8874058671021546032: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:42:51.753] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1595443370974225",
"created_by": "project:chromium",
"created_ts": "1595435185372881",
"id": "8874058671021546032",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b915228be06a91db61457fe0b1a86b811761a000\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/chromium_tests\": {\"bucketed_triggers\": true}, \"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"win-dbg-9-h3ip\", \"branch\": \"refs/heads/master\", \"buildername\": \"win32-official\", \"buildnumber\": 141, \"got_angle_revision\": \"f3c72fdf8246f8bf6673ab34ec0cc17b11107a44\", \"got_dawn_revision\": \"c4cebc7efae1e00f5b15e18aab6c4c0e61c9a1ad\", \"got_nacl_revision\": \"fd20fafa8ec19700e12daa031bf792bae8b6bb21\", \"got_revision\": \"b915228be06a91db61457fe0b1a86b811761a000\", \"got_revision_cp\": \"refs/heads/master@{#790877}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"f272427bda39a1abf3aa6e1a1bdd9b628b5ea5b1\", \"got_v8_revision\": \"724a04e1efe4b873262b9f7e063e8dec886fd609\", \"got_v8_revision_cp\": \"refs/heads/8.6.215@{#1}\", \"got_webrtc_revision\": \"19031583c314ce8ab9cb7cbf115b5706f4a9f9dd\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31781}\", \"mastername\": \"chromium\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b915228be06a91db61457fe0b1a86b811761a000\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_f6b49936a2965b3cdad8f63ce13313d0c4b82119a9acfffbfe199e91c26d0c88_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-9-h3ip\"], \"image\": [\"chrome-win10-1703-20071600-faa8609efbc\"], \"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\": [\"5425-e4e3059\"], \"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": "1595435188745246",
"status": "COMPLETED",
"status_changed_ts": "1595443371398265",
"tags": [
"build_address:luci.chromium.ci/win32-official/141",
"builder:win32-official",
"buildset:commit/git/b915228be06a91db61457fe0b1a86b811761a000",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b915228be06a91db61457fe0b1a86b811761a000",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9039488733029402128",
"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/8874058671021546032/+/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:4d902a810f97bd10",
"user_agent:luci-scheduler"
],
"updated_ts": "1595443371402695",
"url": "https://ci.chromium.org/b/8874058671021546032",
"utcnow_ts": "1595443371745164"
}
}
[18:42:51.753] Invocation finished in 2h16m27.67959788s with status SUCCEEDED