[06:41:26.187] New invocation is queued and will start shortly
[06:41:27.297] Starting the invocation (attempt 1)
[06:41:27.355] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:41:27.355] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:41:27.355] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9068516565099508304",
"parameters_json": "{\"builder_name\":\"ToTiOS\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"44819b642976bfe0b7ff50ed2e79c2b56ae2713f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:ToTiOS",
"scheduler_invocation_id:9068516565099508304",
"scheduler_job_id:chromium/ToTiOS",
"user_agent:luci-scheduler",
"buildset:commit/git/44819b642976bfe0b7ff50ed2e79c2b56ae2713f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/44819b642976bfe0b7ff50ed2e79c2b56ae2713f",
"gitiles_ref:refs/heads/master"
]
}
[06:41:28.711] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1567752087686020",
"id": "8903086502861160176",
"parameters_json": "{\"builder_name\": \"ToTiOS\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"44819b642976bfe0b7ff50ed2e79c2b56ae2713f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1567752088416699",
"tags": [
"build_address:luci.chromium.ci/ToTiOS/9779",
"builder:ToTiOS",
"buildset:commit/git/44819b642976bfe0b7ff50ed2e79c2b56ae2713f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/44819b642976bfe0b7ff50ed2e79c2b56ae2713f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9068516565099508304",
"scheduler_job_id:chromium/ToTiOS",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8903086502861160176/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:ios/unified_builder_tester",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1567752088421194",
"url": "https://ci.chromium.org/b/8903086502861160176",
"utcnow_ts": "1567752088694828"
}
}
[06:41:28.711] Task URL: https://ci.chromium.org/b/8903086502861160176
[06:41:28.711] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:2:0) after 1m0s
[06:42:28.745] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:2:0)
[06:42:28.745] Timer tick, asking Buildbucket for the build status
[06:42:28.968] Build 8903086502861160176: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:42:28.968] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:3:0) after 1m0s
[06:43:29.093] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:3:0)
[06:43:29.093] Timer tick, asking Buildbucket for the build status
[06:43:29.838] Build 8903086502861160176: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:43:29.838] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:4:0) after 1m0s
[06:44:24.829] Received PubSub notification, asking Buildbucket for the build status
[06:44:25.828] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:29.946] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:4:0)
[06:44:29.946] Timer tick, asking Buildbucket for the build status
[06:44:31.481] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:31.481] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:6:0) after 1m0s
[06:45:31.698] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:6:0)
[06:45:31.699] Timer tick, asking Buildbucket for the build status
[06:45:32.312] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:32.312] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:7:0) after 1m0s
[06:46:32.509] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:7:0)
[06:46:32.509] Timer tick, asking Buildbucket for the build status
[06:46:32.790] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:32.790] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:8:0) after 1m0s
[06:47:32.914] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:8:0)
[06:47:32.914] Timer tick, asking Buildbucket for the build status
[06:47:33.092] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:33.092] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:9:0) after 1m0s
[06:48:33.137] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:9:0)
[06:48:33.137] Timer tick, asking Buildbucket for the build status
[06:48:33.568] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:33.568] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:10:0) after 1m0s
[06:49:33.715] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:10:0)
[06:49:33.715] Timer tick, asking Buildbucket for the build status
[06:49:34.273] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:34.273] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:11:0) after 1m0s
[06:50:34.332] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:11:0)
[06:50:34.332] Timer tick, asking Buildbucket for the build status
[06:50:34.608] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:34.608] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:12:0) after 1m0s
[06:51:34.627] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:12:0)
[06:51:34.627] Timer tick, asking Buildbucket for the build status
[06:51:34.876] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:34.876] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:13:0) after 1m0s
[06:52:34.899] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:13:0)
[06:52:34.899] Timer tick, asking Buildbucket for the build status
[06:52:35.241] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:35.241] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:14:0) after 1m0s
[06:53:35.274] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:14:0)
[06:53:35.274] Timer tick, asking Buildbucket for the build status
[06:53:36.306] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:36.306] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:15:0) after 1m0s
[06:54:36.302] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:15:0)
[06:54:36.302] Timer tick, asking Buildbucket for the build status
[06:54:36.616] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:36.616] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:16:0) after 1m0s
[06:55:36.645] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:16:0)
[06:55:36.645] Timer tick, asking Buildbucket for the build status
[06:55:36.868] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:36.868] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:17:0) after 1m0s
[06:56:37.266] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:17:0)
[06:56:37.266] Timer tick, asking Buildbucket for the build status
[06:56:37.858] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:37.858] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:18:0) after 1m0s
[06:57:37.906] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:18:0)
[06:57:37.906] Timer tick, asking Buildbucket for the build status
[06:57:38.261] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:38.261] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:19:0) after 1m0s
[06:58:38.707] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:19:0)
[06:58:38.707] Timer tick, asking Buildbucket for the build status
[06:58:39.298] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:39.298] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:20:0) after 1m0s
[06:59:39.558] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:20:0)
[06:59:39.558] Timer tick, asking Buildbucket for the build status
[06:59:40.282] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:40.282] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:21:0) after 1m0s
[07:00:40.457] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:21:0)
[07:00:40.457] Timer tick, asking Buildbucket for the build status
[07:00:41.642] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:41.642] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:22:0) after 1m0s
[07:01:41.844] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:22:0)
[07:01:41.844] Timer tick, asking Buildbucket for the build status
[07:01:42.058] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:42.058] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:23:0) after 1m0s
[07:02:42.323] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:23:0)
[07:02:42.323] Timer tick, asking Buildbucket for the build status
[07:02:42.610] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:42.610] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:24:0) after 1m0s
[07:03:42.917] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:24:0)
[07:03:42.917] Timer tick, asking Buildbucket for the build status
[07:03:43.809] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:43.809] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:25:0) after 1m0s
[07:04:43.957] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:25:0)
[07:04:43.957] Timer tick, asking Buildbucket for the build status
[07:04:44.725] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:44.725] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:26:0) after 1m0s
[07:05:44.901] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:26:0)
[07:05:44.901] Timer tick, asking Buildbucket for the build status
[07:05:45.435] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:45.435] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:27:0) after 1m0s
[07:06:45.605] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:27:0)
[07:06:45.605] Timer tick, asking Buildbucket for the build status
[07:06:45.933] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:45.933] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:28:0) after 1m0s
[07:07:46.114] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:28:0)
[07:07:46.114] Timer tick, asking Buildbucket for the build status
[07:07:47.614] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:47.614] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:29:0) after 1m0s
[07:08:47.997] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:29:0)
[07:08:47.997] Timer tick, asking Buildbucket for the build status
[07:08:48.821] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:48.821] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:30:0) after 1m0s
[07:09:48.839] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:30:0)
[07:09:48.839] Timer tick, asking Buildbucket for the build status
[07:09:50.836] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:50.836] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:31:0) after 1m0s
[07:10:50.884] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:31:0)
[07:10:50.884] Timer tick, asking Buildbucket for the build status
[07:10:51.122] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:51.122] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:32:0) after 1m0s
[07:11:51.152] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:32:0)
[07:11:51.152] Timer tick, asking Buildbucket for the build status
[07:11:51.466] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:51.466] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:33:0) after 1m0s
[07:12:51.565] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:33:0)
[07:12:51.565] Timer tick, asking Buildbucket for the build status
[07:12:51.830] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:51.830] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:34:0) after 1m0s
[07:13:51.948] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:34:0)
[07:13:51.948] Timer tick, asking Buildbucket for the build status
[07:13:52.501] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:52.501] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:35:0) after 1m0s
[07:14:52.589] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:35:0)
[07:14:52.589] Timer tick, asking Buildbucket for the build status
[07:14:53.127] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:53.127] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:36:0) after 1m0s
[07:15:53.160] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:36:0)
[07:15:53.160] Timer tick, asking Buildbucket for the build status
[07:15:53.412] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:53.412] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:37:0) after 1m0s
[07:16:53.469] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:37:0)
[07:16:53.469] Timer tick, asking Buildbucket for the build status
[07:16:55.713] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:55.713] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:38:0) after 1m0s
[07:17:56.187] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:38:0)
[07:17:56.187] Timer tick, asking Buildbucket for the build status
[07:17:56.360] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:56.360] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:39:0) after 1m0s
[07:18:56.597] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:39:0)
[07:18:56.597] Timer tick, asking Buildbucket for the build status
[07:18:57.196] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:57.196] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:40:0) after 1m0s
[07:19:57.740] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:40:0)
[07:19:57.740] Timer tick, asking Buildbucket for the build status
[07:19:58.328] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:58.328] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:41:0) after 1m0s
[07:20:58.610] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:41:0)
[07:20:58.610] Timer tick, asking Buildbucket for the build status
[07:20:59.285] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:59.285] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:42:0) after 1m0s
[07:21:59.557] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:42:0)
[07:21:59.557] Timer tick, asking Buildbucket for the build status
[07:21:59.759] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:59.759] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:43:0) after 1m0s
[07:23:00.018] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:43:0)
[07:23:00.018] Timer tick, asking Buildbucket for the build status
[07:23:00.440] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:00.440] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:44:0) after 1m0s
[07:24:00.562] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:44:0)
[07:24:00.562] Timer tick, asking Buildbucket for the build status
[07:24:01.300] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:01.300] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:45:0) after 1m0s
[07:25:01.353] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:45:0)
[07:25:01.353] Timer tick, asking Buildbucket for the build status
[07:25:01.725] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:01.725] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:46:0) after 1m0s
[07:26:01.822] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:46:0)
[07:26:01.822] Timer tick, asking Buildbucket for the build status
[07:26:02.039] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:02.039] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:47:0) after 1m0s
[07:27:02.115] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:47:0)
[07:27:02.115] Timer tick, asking Buildbucket for the build status
[07:27:02.641] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:02.641] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:48:0) after 1m0s
[07:28:02.672] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:48:0)
[07:28:02.672] Timer tick, asking Buildbucket for the build status
[07:28:02.855] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:02.855] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:49:0) after 1m0s
[07:29:02.902] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:49:0)
[07:29:02.902] Timer tick, asking Buildbucket for the build status
[07:29:03.352] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:03.352] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:50:0) after 1m0s
[07:30:03.499] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:50:0)
[07:30:03.499] Timer tick, asking Buildbucket for the build status
[07:30:03.734] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:03.734] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:51:0) after 1m0s
[07:31:03.909] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:51:0)
[07:31:03.909] Timer tick, asking Buildbucket for the build status
[07:31:05.229] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:05.229] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:52:0) after 1m0s
[07:32:05.377] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:52:0)
[07:32:05.377] Timer tick, asking Buildbucket for the build status
[07:32:05.613] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:05.613] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:53:0) after 1m0s
[07:33:05.639] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:53:0)
[07:33:05.639] Timer tick, asking Buildbucket for the build status
[07:33:05.865] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:05.865] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:54:0) after 1m0s
[07:34:05.895] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:54:0)
[07:34:05.895] Timer tick, asking Buildbucket for the build status
[07:34:06.125] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:06.125] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:55:0) after 1m0s
[07:35:06.517] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:55:0)
[07:35:06.517] Timer tick, asking Buildbucket for the build status
[07:35:06.813] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:06.813] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:56:0) after 1m0s
[07:36:07.109] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:56:0)
[07:36:07.109] Timer tick, asking Buildbucket for the build status
[07:36:09.547] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:09.547] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:57:0) after 1m0s
[07:37:09.660] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:57:0)
[07:37:09.660] Timer tick, asking Buildbucket for the build status
[07:37:10.112] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:10.112] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:58:0) after 1m0s
[07:38:10.159] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:58:0)
[07:38:10.160] Timer tick, asking Buildbucket for the build status
[07:38:10.807] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:10.807] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:59:0) after 1m0s
[07:39:10.896] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:59:0)
[07:39:10.896] Timer tick, asking Buildbucket for the build status
[07:39:11.488] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:11.488] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:60:0) after 1m0s
[07:40:11.480] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:60:0)
[07:40:11.480] Timer tick, asking Buildbucket for the build status
[07:40:12.259] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:12.259] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:61:0) after 1m0s
[07:41:12.351] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:61:0)
[07:41:12.351] Timer tick, asking Buildbucket for the build status
[07:41:12.845] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:12.845] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:62:0) after 1m0s
[07:42:12.867] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:62:0)
[07:42:12.867] Timer tick, asking Buildbucket for the build status
[07:42:14.803] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:14.803] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:63:0) after 1m0s
[07:43:14.941] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:63:0)
[07:43:14.941] Timer tick, asking Buildbucket for the build status
[07:43:15.232] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:15.232] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:64:0) after 1m0s
[07:44:15.283] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:64:0)
[07:44:15.283] Timer tick, asking Buildbucket for the build status
[07:44:15.643] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:15.643] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:65:0) after 1m0s
[07:45:15.739] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:65:0)
[07:45:15.739] Timer tick, asking Buildbucket for the build status
[07:45:19.512] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:19.512] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:66:0) after 1m0s
[07:46:19.716] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:66:0)
[07:46:19.716] Timer tick, asking Buildbucket for the build status
[07:46:20.466] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:20.466] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:67:0) after 1m0s
[07:47:20.646] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:67:0)
[07:47:20.646] Timer tick, asking Buildbucket for the build status
[07:47:21.075] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:21.075] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:68:0) after 1m0s
[07:48:21.290] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:68:0)
[07:48:21.290] Timer tick, asking Buildbucket for the build status
[07:48:21.836] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:21.836] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:69:0) after 1m0s
[07:49:22.013] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:69:0)
[07:49:22.013] Timer tick, asking Buildbucket for the build status
[07:49:22.895] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:22.895] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:70:0) after 1m0s
[07:50:22.923] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:70:0)
[07:50:22.923] Timer tick, asking Buildbucket for the build status
[07:50:24.119] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:24.119] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:71:0) after 1m0s
[07:51:24.165] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:71:0)
[07:51:24.165] Timer tick, asking Buildbucket for the build status
[07:51:24.487] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:24.487] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:72:0) after 1m0s
[07:52:24.545] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:72:0)
[07:52:24.545] Timer tick, asking Buildbucket for the build status
[07:52:25.293] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:25.293] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:73:0) after 1m0s
[07:53:25.376] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:73:0)
[07:53:25.376] Timer tick, asking Buildbucket for the build status
[07:53:25.920] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:25.920] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:74:0) after 1m0s
[07:54:25.979] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:74:0)
[07:54:25.979] Timer tick, asking Buildbucket for the build status
[07:54:26.142] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:26.142] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:75:0) after 1m0s
[07:55:26.185] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:75:0)
[07:55:26.185] Timer tick, asking Buildbucket for the build status
[07:55:30.506] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:30.506] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:76:0) after 1m0s
[07:56:30.627] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:76:0)
[07:56:30.627] Timer tick, asking Buildbucket for the build status
[07:56:31.564] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:31.564] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:77:0) after 1m0s
[07:57:31.717] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:77:0)
[07:57:31.717] Timer tick, asking Buildbucket for the build status
[07:57:33.631] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:33.631] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:78:0) after 1m0s
[07:58:33.656] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:78:0)
[07:58:33.656] Timer tick, asking Buildbucket for the build status
[07:58:34.368] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:34.368] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:79:0) after 1m0s
[07:59:34.430] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:79:0)
[07:59:34.430] Timer tick, asking Buildbucket for the build status
[07:59:35.210] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:35.210] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:80:0) after 1m0s
[08:00:35.245] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:80:0)
[08:00:35.245] Timer tick, asking Buildbucket for the build status
[08:00:35.483] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:35.483] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:81:0) after 1m0s
[08:01:35.556] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:81:0)
[08:01:35.556] Timer tick, asking Buildbucket for the build status
[08:01:40.342] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:40.342] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:82:0) after 1m0s
[08:02:40.406] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:82:0)
[08:02:40.406] Timer tick, asking Buildbucket for the build status
[08:02:44.347] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:44.347] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:83:0) after 1m0s
[08:03:44.427] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:83:0)
[08:03:44.427] Timer tick, asking Buildbucket for the build status
[08:03:45.375] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:45.375] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:84:0) after 1m0s
[08:04:45.442] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:84:0)
[08:04:45.442] Timer tick, asking Buildbucket for the build status
[08:04:46.101] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:46.101] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:85:0) after 1m0s
[08:05:46.254] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:85:0)
[08:05:46.254] Timer tick, asking Buildbucket for the build status
[08:05:46.669] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:46.669] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:86:0) after 1m0s
[08:06:46.762] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:86:0)
[08:06:46.762] Timer tick, asking Buildbucket for the build status
[08:06:47.054] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:47.054] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:87:0) after 1m0s
[08:07:47.198] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:87:0)
[08:07:47.198] Timer tick, asking Buildbucket for the build status
[08:07:47.460] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:47.460] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:88:0) after 1m0s
[08:08:47.497] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:88:0)
[08:08:47.497] Timer tick, asking Buildbucket for the build status
[08:08:48.643] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:48.643] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:89:0) after 1m0s
[08:09:48.682] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:89:0)
[08:09:48.682] Timer tick, asking Buildbucket for the build status
[08:09:49.550] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:49.550] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:90:0) after 1m0s
[08:10:49.608] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:90:0)
[08:10:49.608] Timer tick, asking Buildbucket for the build status
[08:10:52.745] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:52.745] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:91:0) after 1m0s
[08:11:52.835] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:91:0)
[08:11:52.835] Timer tick, asking Buildbucket for the build status
[08:11:55.008] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:55.008] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:92:0) after 1m0s
[08:12:55.067] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:92:0)
[08:12:55.067] Timer tick, asking Buildbucket for the build status
[08:12:57.132] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:57.132] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:93:0) after 1m0s
[08:13:57.214] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:93:0)
[08:13:57.214] Timer tick, asking Buildbucket for the build status
[08:13:57.767] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:57.767] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:94:0) after 1m0s
[08:14:57.838] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:94:0)
[08:14:57.838] Timer tick, asking Buildbucket for the build status
[08:14:58.609] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:58.609] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:95:0) after 1m0s
[08:15:58.639] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:95:0)
[08:15:58.639] Timer tick, asking Buildbucket for the build status
[08:15:59.100] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:59.100] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:96:0) after 1m0s
[08:16:59.199] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:96:0)
[08:16:59.199] Timer tick, asking Buildbucket for the build status
[08:16:59.502] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:59.502] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:97:0) after 1m0s
[08:17:59.553] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:97:0)
[08:17:59.553] Timer tick, asking Buildbucket for the build status
[08:17:59.787] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:59.788] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:98:0) after 1m0s
[08:18:59.783] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:98:0)
[08:18:59.783] Timer tick, asking Buildbucket for the build status
[08:19:00.147] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:00.147] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:99:0) after 1m0s
[08:20:00.277] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:99:0)
[08:20:00.277] Timer tick, asking Buildbucket for the build status
[08:20:00.475] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:00.475] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:100:0) after 1m0s
[08:21:00.585] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:100:0)
[08:21:00.585] Timer tick, asking Buildbucket for the build status
[08:21:00.862] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:00.862] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:101:0) after 1m0s
[08:22:00.925] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:101:0)
[08:22:00.925] Timer tick, asking Buildbucket for the build status
[08:22:01.582] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:01.583] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:102:0) after 1m0s
[08:23:01.611] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:102:0)
[08:23:01.611] Timer tick, asking Buildbucket for the build status
[08:23:01.855] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:01.855] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:103:0) after 1m0s
[08:24:01.955] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:103:0)
[08:24:01.955] Timer tick, asking Buildbucket for the build status
[08:24:02.435] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:02.435] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:104:0) after 1m0s
[08:25:02.489] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:104:0)
[08:25:02.489] Timer tick, asking Buildbucket for the build status
[08:25:02.745] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:02.745] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:105:0) after 1m0s
[08:26:02.772] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:105:0)
[08:26:02.772] Timer tick, asking Buildbucket for the build status
[08:26:02.962] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:02.962] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:106:0) after 1m0s
[08:27:03.072] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:106:0)
[08:27:03.072] Timer tick, asking Buildbucket for the build status
[08:27:03.350] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:03.350] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:107:0) after 1m0s
[08:28:03.385] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:107:0)
[08:28:03.385] Timer tick, asking Buildbucket for the build status
[08:28:04.259] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:04.259] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:108:0) after 1m0s
[08:29:04.352] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:108:0)
[08:29:04.352] Timer tick, asking Buildbucket for the build status
[08:29:04.919] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:04.919] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:109:0) after 1m0s
[08:30:04.980] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:109:0)
[08:30:04.980] Timer tick, asking Buildbucket for the build status
[08:30:05.335] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:05.335] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:110:0) after 1m0s
[08:31:05.395] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:110:0)
[08:31:05.395] Timer tick, asking Buildbucket for the build status
[08:31:05.834] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:05.834] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:111:0) after 1m0s
[08:32:05.862] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:111:0)
[08:32:05.862] Timer tick, asking Buildbucket for the build status
[08:32:06.211] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:06.211] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:112:0) after 1m0s
[08:33:06.227] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:112:0)
[08:33:06.227] Timer tick, asking Buildbucket for the build status
[08:33:06.599] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:06.599] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:113:0) after 1m0s
[08:34:06.658] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:113:0)
[08:34:06.658] Timer tick, asking Buildbucket for the build status
[08:34:07.611] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:07.611] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:114:0) after 1m0s
[08:35:07.711] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:114:0)
[08:35:07.711] Timer tick, asking Buildbucket for the build status
[08:35:08.249] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:08.249] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:115:0) after 1m0s
[08:36:08.290] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:115:0)
[08:36:08.290] Timer tick, asking Buildbucket for the build status
[08:36:08.516] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:08.516] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:116:0) after 1m0s
[08:37:08.654] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:116:0)
[08:37:08.654] Timer tick, asking Buildbucket for the build status
[08:37:09.062] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:09.062] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:117:0) after 1m0s
[08:38:09.179] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:117:0)
[08:38:09.179] Timer tick, asking Buildbucket for the build status
[08:38:09.444] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:09.444] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:118:0) after 1m0s
[08:39:09.711] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:118:0)
[08:39:09.711] Timer tick, asking Buildbucket for the build status
[08:39:10.852] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:10.852] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:119:0) after 1m0s
[08:40:10.876] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:119:0)
[08:40:10.876] Timer tick, asking Buildbucket for the build status
[08:40:12.509] Build 8903086502861160176: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:12.509] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9068516565099508304:120:0) after 1m0s
[08:40:33.248] Received PubSub notification, asking Buildbucket for the build status
[08:40:35.260] Build 8903086502861160176: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:40:35.260] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1567759232178817",
"created_by": "project:chromium",
"created_ts": "1567752087686020",
"id": "8903086502861160176",
"parameters_json": "{\"builder_name\": \"ToTiOS\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"44819b642976bfe0b7ff50ed2e79c2b56ae2713f\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"build281-m9\", \"branch\": \"refs/heads/master\", \"buildername\": \"ToTiOS\", \"buildnumber\": 9779, \"got_angle_revision\": \"df0ce01870fafa4b0ad2bca6857a853f9567644e\", \"got_dawn_revision\": \"a900ccebcf428c99184ca44a3fad6030b2e43fc7\", \"got_nacl_revision\": \"058a26f57015051953a727add26e7e26348b9558\", \"got_revision\": \"44819b642976bfe0b7ff50ed2e79c2b56ae2713f\", \"got_revision_cp\": \"refs/heads/master@{#694151}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"73694fd32996da7f914e05ebc84aa9fbe2cd6b52\", \"got_v8_revision_cp\": \"refs/heads/7.8.279@{#1}\", \"got_webrtc_revision\": \"d4e6904d408a18c233bdf3a44012e7252eaffbbd\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#29081}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.clang\", \"path_config\": \"generic\", \"perf_dashboard_machine_group\": \"ChromiumClang\", \"recipe\": \"ios/unified_builder_tester\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"44819b642976bfe0b7ff50ed2e79c2b56ae2713f\", \"swarm_hashes\": {\"0\": \"4369809cce3065b7e997ad1ca09046c860c5f1e6\", \"1\": \"f2cf9816ac471ec64c919cf7f3bdf42bf29593c0\", \"10\": \"d95f85cacd29a7229583fed8c428bd0b446be782\", \"11\": \"2f134433301e062ef031cdee602462113a5464b9\", \"12\": \"6fd488d540be90d659764e55bbf672b4dece80cd\", \"13\": \"e5a1dded65b21f742b13e283e6ca88e959be8327\", \"14\": \"a8abbadbf0c10bb90ce4e588a6596d6e35fea2e8\", \"15\": \"7d62177f9f272c74dd386116f79baa6e48612d03\", \"16\": \"e020d381e5fb9c9493c3a650b0e16f25e58c3fce\", \"2\": \"4dfe159be0733801bf74f6d4abfba4155f65c9b8\", \"3\": \"d30693abc1f1ebb32e01487e2f852afc2b57ef62\", \"4\": \"882ea3ca14da5913682269297c344926230f6ae6\", \"5\": \"6dc596819ed11bd28df5f27adbb483dbd86e0de2\", \"6\": \"6a2972c60ca86c8f0c463045d669c660de0046ef\", \"7\": \"491c41178b671c684baf6e2502a3f7d3ef693d11\", \"8\": \"c3370ced10760a0beb7f14eb81030726d52da8c9\", \"9\": \"5cc9a8a26a964014e29844195cc7de1c0251acce\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4a4feefdd7e355061708c01720b06a0ca8c49ffc9968a2ebc674541738676b8f_v2\", \"builder_69caa34b8f39daac28a821cac2619730401d46a6479812cc34796e3bf180418a_v2\", \"builder_ae5debd901c74ee0906916cb62621bceb2a8b657b42c2e2e9e89a0f6a2e5e53b_v2\", \"builder_d42b89eab403033f5a2a8102ec9c436397350cedb439d5e09b11874a5784adb4_v2\", \"git\", \"goma_v2\", \"vpython\", \"xcode_ios_11m382q\", \"xcode_ios_11m392q\", \"xcode_ios_11m392r\"], \"cores\": [\"24\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-E5-2697_v2\"], \"gce\": [\"0\"], \"gpu\": [\"1002\", \"1002:679e\", \"1002:679e-4.0.11-3.2.8\"], \"hidpi\": [\"0\"], \"id\": [\"build281-m9\"], \"mac_model\": [\"MacPro6,1\"], \"machine_type\": [\"n1-standard-16\"], \"os\": [\"Mac\", \"Mac-10.14\", \"Mac-10.14.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.10\"], \"server_version\": [\"4417-ad2503b\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-atl\", \"us-atl-golo\", \"us-atl-golo-m9\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1567752263948410",
"status": "COMPLETED",
"status_changed_ts": "1567759232764072",
"tags": [
"build_address:luci.chromium.ci/ToTiOS/9779",
"builder:ToTiOS",
"buildset:commit/git/44819b642976bfe0b7ff50ed2e79c2b56ae2713f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/44819b642976bfe0b7ff50ed2e79c2b56ae2713f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9068516565099508304",
"scheduler_job_id:chromium/ToTiOS",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8903086502861160176/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Mac",
"swarming_tag:recipe_name:ios/unified_builder_tester",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:471e1ff4c0fd9e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1567759232775529",
"url": "https://ci.chromium.org/b/8903086502861160176",
"utcnow_ts": "1567759235178496"
}
}
[08:40:35.260] Invocation finished in 1h59m9.092751505s with status SUCCEEDED