[21:08:56.937] New invocation is queued and will start shortly
[21:08:58.447] Starting the invocation (attempt 1)
[21:08:58.525] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:08:58.525] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:08:58.525] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9066106464809040672",
"parameters_json": "{\"builder_name\":\"ToTiOS\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"d31a632269e5bbcc2f7ac406f1690acdbbab103b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:ToTiOS",
"scheduler_invocation_id:9066106464809040672",
"scheduler_job_id:chromium/ToTiOS",
"user_agent:luci-scheduler",
"buildset:commit/git/d31a632269e5bbcc2f7ac406f1690acdbbab103b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d31a632269e5bbcc2f7ac406f1690acdbbab103b",
"gitiles_ref:refs/heads/master"
]
}
[21:09:00.855] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1570050538902590",
"id": "8900676402078446128",
"parameters_json": "{\"builder_name\": \"ToTiOS\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"d31a632269e5bbcc2f7ac406f1690acdbbab103b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1570050540680387",
"tags": [
"build_address:luci.chromium.ci/ToTiOS/10188",
"builder:ToTiOS",
"buildset:commit/git/d31a632269e5bbcc2f7ac406f1690acdbbab103b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d31a632269e5bbcc2f7ac406f1690acdbbab103b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9066106464809040672",
"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/8900676402078446128/+/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": "1570050540680539",
"url": "https://ci.chromium.org/b/8900676402078446128",
"utcnow_ts": "1570050540845401"
}
}
[21:09:00.855] Task URL: https://ci.chromium.org/b/8900676402078446128
[21:09:00.855] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:2:0) after 1m0s
[21:10:00.940] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:2:0)
[21:10:00.940] Timer tick, asking Buildbucket for the build status
[21:10:01.271] Build 8900676402078446128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:10:01.271] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:3:0) after 1m0s
[21:11:01.351] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:3:0)
[21:11:01.351] Timer tick, asking Buildbucket for the build status
[21:11:01.744] Build 8900676402078446128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:11:01.744] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:4:0) after 1m0s
[21:12:01.842] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:4:0)
[21:12:01.842] Timer tick, asking Buildbucket for the build status
[21:12:02.010] Build 8900676402078446128: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:12:02.010] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:5:0) after 1m0s
[21:12:09.805] Received PubSub notification, asking Buildbucket for the build status
[21:12:10.460] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:02.094] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:5:0)
[21:13:02.094] Timer tick, asking Buildbucket for the build status
[21:13:02.585] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:02.585] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:7:0) after 1m0s
[21:14:02.651] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:7:0)
[21:14:02.651] Timer tick, asking Buildbucket for the build status
[21:14:06.311] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:06.311] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:8:0) after 1m0s
[21:15:06.355] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:8:0)
[21:15:06.355] Timer tick, asking Buildbucket for the build status
[21:15:06.635] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:06.635] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:9:0) after 1m0s
[21:16:06.784] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:9:0)
[21:16:06.784] Timer tick, asking Buildbucket for the build status
[21:16:07.153] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:07.153] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:10:0) after 1m0s
[21:17:07.233] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:10:0)
[21:17:07.233] Timer tick, asking Buildbucket for the build status
[21:17:07.976] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:07.977] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:11:0) after 1m0s
[21:18:07.983] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:11:0)
[21:18:07.983] Timer tick, asking Buildbucket for the build status
[21:18:08.498] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:08.498] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:12:0) after 1m0s
[21:19:08.545] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:12:0)
[21:19:08.545] Timer tick, asking Buildbucket for the build status
[21:19:09.027] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:09.027] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:13:0) after 1m0s
[21:20:09.056] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:13:0)
[21:20:09.056] Timer tick, asking Buildbucket for the build status
[21:20:09.439] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:09.439] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:14:0) after 1m0s
[21:21:09.579] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:14:0)
[21:21:09.579] Timer tick, asking Buildbucket for the build status
[21:21:09.864] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:09.864] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:15:0) after 1m0s
[21:22:09.951] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:15:0)
[21:22:09.951] Timer tick, asking Buildbucket for the build status
[21:22:10.115] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:10.115] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:16:0) after 1m0s
[21:23:10.165] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:16:0)
[21:23:10.165] Timer tick, asking Buildbucket for the build status
[21:23:10.331] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:10.331] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:17:0) after 1m0s
[21:24:10.479] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:17:0)
[21:24:10.479] Timer tick, asking Buildbucket for the build status
[21:24:10.850] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:10.850] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:18:0) after 1m0s
[21:25:11.098] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:18:0)
[21:25:11.098] Timer tick, asking Buildbucket for the build status
[21:25:12.874] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:12.874] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:19:0) after 1m0s
[21:26:12.932] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:19:0)
[21:26:12.932] Timer tick, asking Buildbucket for the build status
[21:26:13.200] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:13.200] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:20:0) after 1m0s
[21:27:13.334] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:20:0)
[21:27:13.334] Timer tick, asking Buildbucket for the build status
[21:27:13.899] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:13.899] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:21:0) after 1m0s
[21:28:14.122] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:21:0)
[21:28:14.122] Timer tick, asking Buildbucket for the build status
[21:28:14.830] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:14.830] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:22:0) after 1m0s
[21:29:14.895] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:22:0)
[21:29:14.895] Timer tick, asking Buildbucket for the build status
[21:29:15.595] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:15.595] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:23:0) after 1m0s
[21:30:15.659] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:23:0)
[21:30:15.659] Timer tick, asking Buildbucket for the build status
[21:30:15.891] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:15.891] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:24:0) after 1m0s
[21:31:15.918] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:24:0)
[21:31:15.918] Timer tick, asking Buildbucket for the build status
[21:31:16.883] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:16.883] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:25:0) after 1m0s
[21:32:17.128] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:25:0)
[21:32:17.128] Timer tick, asking Buildbucket for the build status
[21:32:17.956] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:17.956] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:26:0) after 1m0s
[21:33:18.160] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:26:0)
[21:33:18.160] Timer tick, asking Buildbucket for the build status
[21:33:18.801] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:18.801] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:27:0) after 1m0s
[21:34:18.942] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:27:0)
[21:34:18.942] Timer tick, asking Buildbucket for the build status
[21:34:19.126] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:19.126] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:28:0) after 1m0s
[21:35:19.201] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:28:0)
[21:35:19.201] Timer tick, asking Buildbucket for the build status
[21:35:20.678] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:20.678] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:29:0) after 1m0s
[21:36:20.804] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:29:0)
[21:36:20.804] Timer tick, asking Buildbucket for the build status
[21:36:21.718] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:21.718] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:30:0) after 1m0s
[21:37:21.785] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:30:0)
[21:37:21.785] Timer tick, asking Buildbucket for the build status
[21:37:21.977] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:21.977] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:31:0) after 1m0s
[21:38:22.122] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:31:0)
[21:38:22.122] Timer tick, asking Buildbucket for the build status
[21:38:22.691] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:22.691] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:32:0) after 1m0s
[21:39:22.747] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:32:0)
[21:39:22.747] Timer tick, asking Buildbucket for the build status
[21:39:24.028] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:24.028] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:33:0) after 1m0s
[21:40:24.211] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:33:0)
[21:40:24.211] Timer tick, asking Buildbucket for the build status
[21:40:24.383] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:24.383] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:34:0) after 1m0s
[21:41:24.547] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:34:0)
[21:41:24.547] Timer tick, asking Buildbucket for the build status
[21:41:25.989] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:25.989] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:35:0) after 1m0s
[21:42:26.008] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:35:0)
[21:42:26.008] Timer tick, asking Buildbucket for the build status
[21:42:26.364] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:26.364] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:36:0) after 1m0s
[21:43:26.477] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:36:0)
[21:43:26.477] Timer tick, asking Buildbucket for the build status
[21:43:27.688] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:27.688] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:37:0) after 1m0s
[21:44:27.726] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:37:0)
[21:44:27.726] Timer tick, asking Buildbucket for the build status
[21:44:28.615] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:28.615] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:38:0) after 1m0s
[21:45:28.754] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:38:0)
[21:45:28.754] Timer tick, asking Buildbucket for the build status
[21:45:29.056] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:29.056] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:39:0) after 1m0s
[21:46:29.218] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:39:0)
[21:46:29.218] Timer tick, asking Buildbucket for the build status
[21:46:29.358] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:29.359] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:40:0) after 1m0s
[21:47:29.409] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:40:0)
[21:47:29.409] Timer tick, asking Buildbucket for the build status
[21:47:29.777] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:29.777] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:41:0) after 1m0s
[21:48:29.800] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:41:0)
[21:48:29.800] Timer tick, asking Buildbucket for the build status
[21:48:31.124] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:31.124] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:42:0) after 1m0s
[21:49:31.185] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:42:0)
[21:49:31.185] Timer tick, asking Buildbucket for the build status
[21:49:33.199] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:33.199] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:43:0) after 1m0s
[21:50:33.302] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:43:0)
[21:50:33.302] Timer tick, asking Buildbucket for the build status
[21:50:33.869] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:33.869] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:44:0) after 1m0s
[21:51:33.905] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:44:0)
[21:51:33.905] Timer tick, asking Buildbucket for the build status
[21:51:34.198] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:34.198] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:45:0) after 1m0s
[21:52:34.338] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:45:0)
[21:52:34.338] Timer tick, asking Buildbucket for the build status
[21:52:35.042] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:35.042] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:46:0) after 1m0s
[21:53:35.145] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:46:0)
[21:53:35.145] Timer tick, asking Buildbucket for the build status
[21:53:35.436] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:35.436] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:47:0) after 1m0s
[21:54:35.448] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:47:0)
[21:54:35.448] Timer tick, asking Buildbucket for the build status
[21:54:36.891] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:36.891] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:48:0) after 1m0s
[21:55:36.972] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:48:0)
[21:55:36.972] Timer tick, asking Buildbucket for the build status
[21:55:37.871] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:37.871] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:49:0) after 1m0s
[21:56:38.204] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:49:0)
[21:56:38.204] Timer tick, asking Buildbucket for the build status
[21:56:38.445] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:38.445] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:50:0) after 1m0s
[21:57:38.554] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:50:0)
[21:57:38.554] Timer tick, asking Buildbucket for the build status
[21:57:38.836] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:38.836] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:51:0) after 1m0s
[21:58:38.858] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:51:0)
[21:58:38.858] Timer tick, asking Buildbucket for the build status
[21:58:39.703] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:39.703] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:52:0) after 1m0s
[21:59:39.970] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:52:0)
[21:59:39.970] Timer tick, asking Buildbucket for the build status
[21:59:40.314] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:40.314] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:53:0) after 1m0s
[22:00:40.376] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:53:0)
[22:00:40.376] Timer tick, asking Buildbucket for the build status
[22:00:41.255] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:41.255] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:54:0) after 1m0s
[22:01:41.405] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:54:0)
[22:01:41.405] Timer tick, asking Buildbucket for the build status
[22:01:42.509] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:42.509] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:55:0) after 1m0s
[22:02:42.569] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:55:0)
[22:02:42.569] Timer tick, asking Buildbucket for the build status
[22:02:42.790] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:42.790] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:56:0) after 1m0s
[22:03:42.849] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:56:0)
[22:03:42.849] Timer tick, asking Buildbucket for the build status
[22:03:43.053] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:43.053] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:57:0) after 1m0s
[22:04:43.289] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:57:0)
[22:04:43.289] Timer tick, asking Buildbucket for the build status
[22:04:43.818] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:43.818] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:58:0) after 1m0s
[22:05:43.916] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:58:0)
[22:05:43.916] Timer tick, asking Buildbucket for the build status
[22:05:46.106] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:46.106] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:59:0) after 1m0s
[22:06:46.166] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:59:0)
[22:06:46.166] Timer tick, asking Buildbucket for the build status
[22:06:46.327] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:46.327] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:60:0) after 1m0s
[22:07:46.435] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:60:0)
[22:07:46.435] Timer tick, asking Buildbucket for the build status
[22:07:46.828] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:46.828] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:61:0) after 1m0s
[22:08:46.894] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:61:0)
[22:08:46.894] Timer tick, asking Buildbucket for the build status
[22:08:48.172] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:48.172] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:62:0) after 1m0s
[22:09:48.286] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:62:0)
[22:09:48.286] Timer tick, asking Buildbucket for the build status
[22:09:54.895] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:54.895] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:63:0) after 1m0s
[22:10:54.971] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:63:0)
[22:10:54.972] Timer tick, asking Buildbucket for the build status
[22:10:55.135] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:55.135] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:64:0) after 1m0s
[22:11:55.252] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:64:0)
[22:11:55.252] Timer tick, asking Buildbucket for the build status
[22:11:57.837] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:57.837] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:65:0) after 1m0s
[22:12:57.920] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:65:0)
[22:12:57.920] Timer tick, asking Buildbucket for the build status
[22:12:58.055] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:58.055] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:66:0) after 1m0s
[22:13:58.135] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:66:0)
[22:13:58.135] Timer tick, asking Buildbucket for the build status
[22:14:02.240] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:02.240] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:67:0) after 1m0s
[22:15:02.354] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:67:0)
[22:15:02.354] Timer tick, asking Buildbucket for the build status
[22:15:02.585] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:02.589] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:68:0) after 1m0s
[22:16:02.645] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:68:0)
[22:16:02.645] Timer tick, asking Buildbucket for the build status
[22:16:02.869] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:02.869] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:69:0) after 1m0s
[22:17:02.881] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:69:0)
[22:17:02.881] Timer tick, asking Buildbucket for the build status
[22:17:03.472] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:03.472] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:70:0) after 1m0s
[22:18:03.534] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:70:0)
[22:18:03.534] Timer tick, asking Buildbucket for the build status
[22:18:03.816] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:03.816] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:71:0) after 1m0s
[22:19:03.853] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:71:0)
[22:19:03.853] Timer tick, asking Buildbucket for the build status
[22:19:04.453] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:04.453] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:72:0) after 1m0s
[22:20:04.539] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:72:0)
[22:20:04.539] Timer tick, asking Buildbucket for the build status
[22:20:04.772] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:04.772] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:73:0) after 1m0s
[22:21:04.775] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:73:0)
[22:21:04.775] Timer tick, asking Buildbucket for the build status
[22:21:05.535] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:05.535] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:74:0) after 1m0s
[22:22:05.592] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:74:0)
[22:22:05.592] Timer tick, asking Buildbucket for the build status
[22:22:05.812] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:05.812] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:75:0) after 1m0s
[22:23:05.952] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:75:0)
[22:23:05.952] Timer tick, asking Buildbucket for the build status
[22:23:06.117] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:06.117] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:76:0) after 1m0s
[22:24:06.270] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:76:0)
[22:24:06.270] Timer tick, asking Buildbucket for the build status
[22:24:08.893] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:08.893] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:77:0) after 1m0s
[22:25:08.964] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:77:0)
[22:25:08.964] Timer tick, asking Buildbucket for the build status
[22:25:09.131] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:09.131] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:78:0) after 1m0s
[22:26:09.253] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:78:0)
[22:26:09.253] Timer tick, asking Buildbucket for the build status
[22:26:09.570] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:09.570] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:79:0) after 1m0s
[22:27:09.624] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:79:0)
[22:27:09.624] Timer tick, asking Buildbucket for the build status
[22:27:09.792] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:09.792] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:80:0) after 1m0s
[22:28:09.939] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:80:0)
[22:28:09.939] Timer tick, asking Buildbucket for the build status
[22:28:12.395] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:12.395] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:81:0) after 1m0s
[22:29:12.463] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:81:0)
[22:29:12.464] Timer tick, asking Buildbucket for the build status
[22:29:15.181] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:15.181] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:82:0) after 1m0s
[22:30:15.396] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:82:0)
[22:30:15.396] Timer tick, asking Buildbucket for the build status
[22:30:15.942] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:15.942] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:83:0) after 1m0s
[22:31:16.278] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:83:0)
[22:31:16.278] Timer tick, asking Buildbucket for the build status
[22:31:17.113] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:17.113] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:84:0) after 1m0s
[22:32:17.303] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:84:0)
[22:32:17.303] Timer tick, asking Buildbucket for the build status
[22:32:17.752] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:17.752] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:85:0) after 1m0s
[22:33:18.088] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:85:0)
[22:33:18.088] Timer tick, asking Buildbucket for the build status
[22:33:18.578] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:18.578] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:86:0) after 1m0s
[22:34:18.806] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:86:0)
[22:34:18.806] Timer tick, asking Buildbucket for the build status
[22:34:21.438] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:21.438] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:87:0) after 1m0s
[22:35:21.534] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:87:0)
[22:35:21.534] Timer tick, asking Buildbucket for the build status
[22:35:22.125] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:22.125] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:88:0) after 1m0s
[22:36:22.194] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:88:0)
[22:36:22.194] Timer tick, asking Buildbucket for the build status
[22:36:22.525] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:22.525] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:89:0) after 1m0s
[22:37:22.719] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:89:0)
[22:37:22.719] Timer tick, asking Buildbucket for the build status
[22:37:23.068] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:23.068] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:90:0) after 1m0s
[22:38:23.099] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:90:0)
[22:38:23.099] Timer tick, asking Buildbucket for the build status
[22:38:25.075] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:25.075] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:91:0) after 1m0s
[22:39:25.167] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:91:0)
[22:39:25.167] Timer tick, asking Buildbucket for the build status
[22:39:25.335] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:25.335] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:92:0) after 1m0s
[22:40:25.439] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:92:0)
[22:40:25.439] Timer tick, asking Buildbucket for the build status
[22:40:25.594] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:25.594] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:93:0) after 1m0s
[22:41:25.624] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:93:0)
[22:41:25.624] Timer tick, asking Buildbucket for the build status
[22:41:27.125] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:27.125] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:94:0) after 1m0s
[22:42:27.352] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:94:0)
[22:42:27.352] Timer tick, asking Buildbucket for the build status
[22:42:28.320] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:28.320] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:95:0) after 1m0s
[22:43:28.418] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:95:0)
[22:43:28.418] Timer tick, asking Buildbucket for the build status
[22:43:29.146] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:29.146] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:96:0) after 1m0s
[22:44:29.178] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:96:0)
[22:44:29.178] Timer tick, asking Buildbucket for the build status
[22:44:29.837] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:29.837] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:97:0) after 1m0s
[22:45:29.866] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:97:0)
[22:45:29.866] Timer tick, asking Buildbucket for the build status
[22:45:30.124] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:30.124] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:98:0) after 1m0s
[22:46:30.161] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:98:0)
[22:46:30.161] Timer tick, asking Buildbucket for the build status
[22:46:30.269] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:30.269] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:99:0) after 1m0s
[22:47:30.293] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:99:0)
[22:47:30.293] Timer tick, asking Buildbucket for the build status
[22:47:30.639] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:30.639] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:100:0) after 1m0s
[22:48:30.676] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:100:0)
[22:48:30.676] Timer tick, asking Buildbucket for the build status
[22:48:31.368] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:31.368] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:101:0) after 1m0s
[22:49:31.447] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:101:0)
[22:49:31.447] Timer tick, asking Buildbucket for the build status
[22:49:32.076] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:32.076] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:102:0) after 1m0s
[22:50:32.154] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:102:0)
[22:50:32.154] Timer tick, asking Buildbucket for the build status
[22:50:32.981] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:32.981] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:103:0) after 1m0s
[22:51:33.089] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:103:0)
[22:51:33.089] Timer tick, asking Buildbucket for the build status
[22:51:33.342] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:33.343] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:104:0) after 1m0s
[22:52:33.457] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:104:0)
[22:52:33.457] Timer tick, asking Buildbucket for the build status
[22:52:35.274] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:35.274] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:105:0) after 1m0s
[22:53:35.356] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:105:0)
[22:53:35.356] Timer tick, asking Buildbucket for the build status
[22:53:35.713] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:35.717] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:106:0) after 1m0s
[22:54:35.909] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:106:0)
[22:54:35.909] Timer tick, asking Buildbucket for the build status
[22:54:36.397] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:36.397] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:107:0) after 1m0s
[22:55:36.548] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:107:0)
[22:55:36.548] Timer tick, asking Buildbucket for the build status
[22:55:36.746] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:36.746] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:108:0) after 1m0s
[22:56:37.154] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:108:0)
[22:56:37.154] Timer tick, asking Buildbucket for the build status
[22:56:37.357] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:37.357] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:109:0) after 1m0s
[22:57:37.588] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:109:0)
[22:57:37.588] Timer tick, asking Buildbucket for the build status
[22:57:39.238] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:39.238] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:110:0) after 1m0s
[22:58:39.595] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:110:0)
[22:58:39.595] Timer tick, asking Buildbucket for the build status
[22:58:39.838] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:39.838] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:111:0) after 1m0s
[22:59:40.018] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:111:0)
[22:59:40.018] Timer tick, asking Buildbucket for the build status
[22:59:40.188] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:40.188] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:112:0) after 1m0s
[23:00:40.581] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:112:0)
[23:00:40.582] Timer tick, asking Buildbucket for the build status
[23:00:41.235] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:41.235] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:113:0) after 1m0s
[23:01:41.310] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:113:0)
[23:01:41.310] Timer tick, asking Buildbucket for the build status
[23:01:41.567] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:41.567] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:114:0) after 1m0s
[23:02:41.699] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:114:0)
[23:02:41.699] Timer tick, asking Buildbucket for the build status
[23:02:41.905] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:41.905] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:115:0) after 1m0s
[23:03:41.979] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:115:0)
[23:03:41.979] Timer tick, asking Buildbucket for the build status
[23:03:42.180] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:42.180] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:116:0) after 1m0s
[23:04:42.418] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:116:0)
[23:04:42.418] Timer tick, asking Buildbucket for the build status
[23:04:42.661] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:42.661] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:117:0) after 1m0s
[23:05:42.804] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:117:0)
[23:05:42.804] Timer tick, asking Buildbucket for the build status
[23:05:43.047] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:43.047] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:118:0) after 1m0s
[23:06:43.182] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:118:0)
[23:06:43.182] Timer tick, asking Buildbucket for the build status
[23:06:44.218] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:44.219] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:119:0) after 1m0s
[23:07:44.307] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:119:0)
[23:07:44.307] Timer tick, asking Buildbucket for the build status
[23:07:44.988] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:44.988] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:120:0) after 1m0s
[23:08:45.072] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:120:0)
[23:08:45.072] Timer tick, asking Buildbucket for the build status
[23:08:45.319] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:45.319] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:121:0) after 1m0s
[23:09:45.421] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:121:0)
[23:09:45.421] Timer tick, asking Buildbucket for the build status
[23:09:47.072] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:47.072] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:122:0) after 1m0s
[23:10:47.291] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:122:0)
[23:10:47.293] Timer tick, asking Buildbucket for the build status
[23:10:47.681] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:47.681] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:123:0) after 1m0s
[23:11:47.792] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:123:0)
[23:11:47.792] Timer tick, asking Buildbucket for the build status
[23:11:48.619] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:48.619] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:124:0) after 1m0s
[23:12:48.760] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:124:0)
[23:12:48.760] Timer tick, asking Buildbucket for the build status
[23:12:51.366] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:51.366] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:125:0) after 1m0s
[23:13:51.429] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:125:0)
[23:13:51.429] Timer tick, asking Buildbucket for the build status
[23:13:51.642] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:51.642] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:126:0) after 1m0s
[23:14:51.667] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:126:0)
[23:14:51.667] Timer tick, asking Buildbucket for the build status
[23:14:52.212] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:52.212] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:127:0) after 1m0s
[23:15:52.252] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:127:0)
[23:15:52.252] Timer tick, asking Buildbucket for the build status
[23:15:52.804] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:52.804] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:128:0) after 1m0s
[23:16:52.947] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:128:0)
[23:16:52.947] Timer tick, asking Buildbucket for the build status
[23:16:53.226] Build 8900676402078446128: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:53.226] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066106464809040672:129:0) after 1m0s
[23:17:18.092] Received PubSub notification, asking Buildbucket for the build status
[23:17:21.596] Build 8900676402078446128: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[23:17:21.596] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1570058237662559",
"created_by": "project:chromium",
"created_ts": "1570050538902590",
"failure_reason": "BUILD_FAILURE",
"id": "8900676402078446128",
"parameters_json": "{\"builder_name\": \"ToTiOS\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"d31a632269e5bbcc2f7ac406f1690acdbbab103b\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Failed ios_web_inttests (iPhone 6s iOS 12.2).\"}, \"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\": \"build285-m9\", \"branch\": \"refs/heads/master\", \"buildername\": \"ToTiOS\", \"buildnumber\": 10188, \"got_angle_revision\": \"d46e2fb1e341a7ba7da56072658d3b4b1a540077\", \"got_dawn_revision\": \"867f72058a4f6b901524efe484611e1e319c80fb\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"d31a632269e5bbcc2f7ac406f1690acdbbab103b\", \"got_revision_cp\": \"refs/heads/master@{#702184}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_v8_revision\": \"bbb8c722a29c653945c231e6361ce46972167464\", \"got_v8_revision_cp\": \"refs/heads/7.9.195@{#1}\", \"got_webrtc_revision\": \"f1e97b9ebd23c12d12ffd6b18bdf3eb4951153b4\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#29367}\", \"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\": \"d31a632269e5bbcc2f7ac406f1690acdbbab103b\", \"swarm_hashes\": {\"0\": \"b6ca9ade7bcb09d43465dd701a392e297e5817ae\", \"1\": \"ba0512061d186b0fad0330da4e5f96e2dcbd4ccb\", \"10\": \"55235f3f8e4e5a4bb337e091707db6f3e31ded7f\", \"11\": \"f31e37bfc75cf241ad6097449d85bc0b9a483025\", \"12\": \"05b5137604204824c6821cdf4b27974be9f97f7c\", \"13\": \"4ef349fd870cb0b5df31031f6b917aea1f1921de\", \"14\": \"733dd56a5058e0f04804a897772adcfed0c9966e\", \"15\": \"65af3c30bd90efcdee77f4492465d0b698071cda\", \"16\": \"4f63e1611668214e3e532c88de6508268e944e85\", \"2\": \"a437f3b81aa7db0b18ef42d5f3159a829110f9c3\", \"3\": \"14deb241303e6291ca0a0d0103362b27a64d1ccd\", \"4\": \"7200eedb35044a2736d8c898b1699c44ba49251d\", \"5\": \"4959c96330387f753b049d1223c0b5542f6c164d\", \"6\": \"3ebb677cad823240435f5ff2e779640d60910f9e\", \"7\": \"fdac2f59b374688acce149966a92dcd28c87eb84\", \"8\": \"ea517d1e5d581dc81f1d722b136d2ec65ee096e1\", \"9\": \"9ccee49b363571b45c1ff2b2fb917f48320d961a\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4a4feefdd7e355061708c01720b06a0ca8c49ffc9968a2ebc674541738676b8f_v2\", \"builder_5372538a354f163a20d332d881c6c5d85e35654ac8a57c760866f12033f8a8a5_v2\", \"builder_69caa34b8f39daac28a821cac2619730401d46a6479812cc34796e3bf180418a_v2\", \"builder_ae5debd901c74ee0906916cb62621bceb2a8b657b42c2e2e9e89a0f6a2e5e53b_v2\", \"builder_d42b89eab403033f5a2a8102ec9c436397350cedb439d5e09b11874a5784adb4_v2\", \"git\", \"goma_v2\", \"vpython\", \"xcode_ios_11a419c\", \"xcode_ios_11a420a\"], \"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\": [\"build285-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\": [\"4466-755d6a2\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-atl\", \"us-atl-golo\", \"us-atl-golo-m9\"]}}, \"ui\": {\"info\": \"Failed ios_web_inttests (iPhone 6s iOS 12.2).\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1570050728307097",
"status": "COMPLETED",
"status_changed_ts": "1570050729000214",
"tags": [
"build_address:luci.chromium.ci/ToTiOS/10188",
"builder:ToTiOS",
"buildset:commit/git/d31a632269e5bbcc2f7ac406f1690acdbbab103b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d31a632269e5bbcc2f7ac406f1690acdbbab103b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9066106464809040672",
"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/8900676402078446128/+/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:47a720a2e265ad10",
"user_agent:luci-scheduler"
],
"updated_ts": "1570058237774785",
"url": "https://ci.chromium.org/b/8900676402078446128",
"utcnow_ts": "1570058241572634"
}
}
[23:17:21.596] Invocation finished in 2h8m24.682334414s with status FAILED