[07:16:36.271] New invocation is queued and will start shortly
[07:16:37.446] Starting the invocation (attempt 1)
[07:16:37.481] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:16:37.481] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:16:37.481] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9078389421855283264",
"parameters_json": "{\"builder_name\":\"ToTiOS\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"3c128de55a845205b64c6d35edb8a9b1ce33ac12\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:ToTiOS",
"scheduler_invocation_id:9078389421855283264",
"scheduler_job_id:chromium/ToTiOS",
"user_agent:luci-scheduler",
"buildset:commit/git/3c128de55a845205b64c6d35edb8a9b1ce33ac12",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3c128de55a845205b64c6d35edb8a9b1ce33ac12",
"gitiles_ref:refs/heads/master"
]
}
[07:16:43.699] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1558336599082280",
"id": "8912959358239025504",
"parameters_json": "{\"builder_name\": \"ToTiOS\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"3c128de55a845205b64c6d35edb8a9b1ce33ac12\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1558336603141685",
"tags": [
"build_address:luci.chromium.ci/ToTiOS/7796",
"builder:ToTiOS",
"buildset:commit/git/3c128de55a845205b64c6d35edb8a9b1ce33ac12",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3c128de55a845205b64c6d35edb8a9b1ce33ac12",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078389421855283264",
"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/8912959358239025504/+/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": "1558336603141788",
"url": "https://ci.chromium.org/b/8912959358239025504",
"utcnow_ts": "1558336603670289"
}
}
[07:16:43.699] Task URL: https://ci.chromium.org/b/8912959358239025504
[07:16:43.699] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:2:0) after 1m0s
[07:17:43.714] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:2:0)
[07:17:43.714] Timer tick, asking Buildbucket for the build status
[07:17:44.231] Build 8912959358239025504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:17:44.231] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:3:0) after 1m0s
[07:18:44.250] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:3:0)
[07:18:44.250] Timer tick, asking Buildbucket for the build status
[07:18:44.662] Build 8912959358239025504: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:18:44.662] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:4:0) after 1m0s
[07:18:59.142] Received PubSub notification, asking Buildbucket for the build status
[07:19:00.312] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:44.683] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:4:0)
[07:19:44.683] Timer tick, asking Buildbucket for the build status
[07:19:46.722] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:46.722] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:6:0) after 1m0s
[07:20:46.742] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:6:0)
[07:20:46.742] Timer tick, asking Buildbucket for the build status
[07:20:46.939] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:46.939] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:7:0) after 1m0s
[07:21:46.944] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:7:0)
[07:21:46.944] Timer tick, asking Buildbucket for the build status
[07:21:47.256] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:47.256] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:8:0) after 1m0s
[07:22:47.296] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:8:0)
[07:22:47.296] Timer tick, asking Buildbucket for the build status
[07:22:47.435] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:47.435] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:9:0) after 1m0s
[07:23:47.454] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:9:0)
[07:23:47.454] Timer tick, asking Buildbucket for the build status
[07:23:49.722] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:49.723] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:10:0) after 1m0s
[07:24:49.742] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:10:0)
[07:24:49.742] Timer tick, asking Buildbucket for the build status
[07:24:49.900] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:49.900] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:11:0) after 1m0s
[07:25:49.921] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:11:0)
[07:25:49.921] Timer tick, asking Buildbucket for the build status
[07:25:50.127] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:50.127] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:12:0) after 1m0s
[07:26:50.145] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:12:0)
[07:26:50.145] Timer tick, asking Buildbucket for the build status
[07:26:50.254] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:50.254] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:13:0) after 1m0s
[07:27:50.271] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:13:0)
[07:27:50.271] Timer tick, asking Buildbucket for the build status
[07:27:50.426] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:50.426] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:14:0) after 1m0s
[07:28:50.475] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:14:0)
[07:28:50.475] Timer tick, asking Buildbucket for the build status
[07:28:50.660] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:50.664] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:15:0) after 1m0s
[07:29:50.694] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:15:0)
[07:29:50.694] Timer tick, asking Buildbucket for the build status
[07:29:51.107] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:51.108] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:16:0) after 1m0s
[07:30:51.128] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:16:0)
[07:30:51.128] Timer tick, asking Buildbucket for the build status
[07:30:51.988] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:51.988] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:17:0) after 1m0s
[07:31:52.033] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:17:0)
[07:31:52.033] Timer tick, asking Buildbucket for the build status
[07:31:54.019] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:54.019] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:18:0) after 1m0s
[07:32:54.039] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:18:0)
[07:32:54.039] Timer tick, asking Buildbucket for the build status
[07:32:54.587] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:54.587] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:19:0) after 1m0s
[07:33:54.609] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:19:0)
[07:33:54.609] Timer tick, asking Buildbucket for the build status
[07:33:55.168] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:55.168] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:20:0) after 1m0s
[07:34:55.199] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:20:0)
[07:34:55.199] Timer tick, asking Buildbucket for the build status
[07:34:55.916] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:55.916] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:21:0) after 1m0s
[07:35:55.948] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:21:0)
[07:35:55.948] Timer tick, asking Buildbucket for the build status
[07:35:56.072] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:56.072] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:22:0) after 1m0s
[07:36:56.078] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:22:0)
[07:36:56.078] Timer tick, asking Buildbucket for the build status
[07:36:56.806] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:56.806] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:23:0) after 1m0s
[07:37:56.871] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:23:0)
[07:37:56.871] Timer tick, asking Buildbucket for the build status
[07:37:57.982] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:57.982] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:24:0) after 1m0s
[07:38:58.027] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:24:0)
[07:38:58.027] Timer tick, asking Buildbucket for the build status
[07:38:59.594] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:59.594] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:25:0) after 1m0s
[07:39:59.614] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:25:0)
[07:39:59.614] Timer tick, asking Buildbucket for the build status
[07:39:59.738] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:59.738] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:26:0) after 1m0s
[07:40:59.715] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:26:0)
[07:40:59.715] Timer tick, asking Buildbucket for the build status
[07:41:01.714] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:01.715] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:27:0) after 1m0s
[07:42:01.738] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:27:0)
[07:42:01.738] Timer tick, asking Buildbucket for the build status
[07:42:02.034] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:02.034] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:28:0) after 1m0s
[07:43:02.037] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:28:0)
[07:43:02.037] Timer tick, asking Buildbucket for the build status
[07:43:02.896] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:02.896] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:29:0) after 1m0s
[07:44:02.916] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:29:0)
[07:44:02.916] Timer tick, asking Buildbucket for the build status
[07:44:03.498] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:03.498] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:30:0) after 1m0s
[07:45:03.501] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:30:0)
[07:45:03.501] Timer tick, asking Buildbucket for the build status
[07:45:03.599] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:03.599] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:31:0) after 1m0s
[07:46:03.626] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:31:0)
[07:46:03.626] Timer tick, asking Buildbucket for the build status
[07:46:03.865] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:03.865] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:32:0) after 1m0s
[07:47:03.885] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:32:0)
[07:47:03.885] Timer tick, asking Buildbucket for the build status
[07:47:06.375] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:06.375] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:33:0) after 1m0s
[07:48:06.713] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:33:0)
[07:48:06.713] Timer tick, asking Buildbucket for the build status
[07:48:07.497] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:07.497] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:34:0) after 1m0s
[07:49:08.435] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:34:0)
[07:49:08.435] Timer tick, asking Buildbucket for the build status
[07:49:08.550] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:08.550] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:35:0) after 1m0s
[07:50:08.546] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:35:0)
[07:50:08.546] Timer tick, asking Buildbucket for the build status
[07:50:08.909] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:08.909] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:36:0) after 1m0s
[07:51:08.933] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:36:0)
[07:51:08.933] Timer tick, asking Buildbucket for the build status
[07:51:09.319] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:09.319] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:37:0) after 1m0s
[07:52:09.344] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:37:0)
[07:52:09.344] Timer tick, asking Buildbucket for the build status
[07:52:09.585] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:09.585] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:38:0) after 1m0s
[07:53:09.589] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:38:0)
[07:53:09.589] Timer tick, asking Buildbucket for the build status
[07:53:09.729] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:09.729] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:39:0) after 1m0s
[07:54:09.769] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:39:0)
[07:54:09.769] Timer tick, asking Buildbucket for the build status
[07:54:11.504] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:11.504] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:40:0) after 1m0s
[07:55:11.530] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:40:0)
[07:55:11.530] Timer tick, asking Buildbucket for the build status
[07:55:11.706] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:11.706] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:41:0) after 1m0s
[07:56:11.726] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:41:0)
[07:56:11.726] Timer tick, asking Buildbucket for the build status
[07:56:12.346] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:12.346] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:42:0) after 1m0s
[07:57:12.410] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:42:0)
[07:57:12.410] Timer tick, asking Buildbucket for the build status
[07:57:12.554] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:12.554] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:43:0) after 1m0s
[07:58:13.624] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:43:0)
[07:58:13.624] Timer tick, asking Buildbucket for the build status
[07:58:20.630] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:20.630] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:44:0) after 1m0s
[07:59:21.302] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:44:0)
[07:59:21.302] Timer tick, asking Buildbucket for the build status
[07:59:21.497] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:21.497] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:45:0) after 1m0s
[08:00:21.564] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:45:0)
[08:00:21.564] Timer tick, asking Buildbucket for the build status
[08:00:21.781] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:21.781] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:46:0) after 1m0s
[08:01:21.807] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:46:0)
[08:01:21.807] Timer tick, asking Buildbucket for the build status
[08:01:22.003] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:22.003] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:47:0) after 1m0s
[08:02:22.084] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:47:0)
[08:02:22.084] Timer tick, asking Buildbucket for the build status
[08:02:22.212] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:22.212] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:48:0) after 1m0s
[08:03:22.300] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:48:0)
[08:03:22.300] Timer tick, asking Buildbucket for the build status
[08:03:24.080] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:24.080] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:49:0) after 1m0s
[08:04:24.098] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:49:0)
[08:04:24.098] Timer tick, asking Buildbucket for the build status
[08:04:24.356] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:24.356] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:50:0) after 1m0s
[08:05:24.393] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:50:0)
[08:05:24.393] Timer tick, asking Buildbucket for the build status
[08:05:24.532] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:24.532] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:51:0) after 1m0s
[08:06:24.675] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:51:0)
[08:06:24.675] Timer tick, asking Buildbucket for the build status
[08:06:25.929] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:25.929] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:52:0) after 1m0s
[08:07:25.969] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:52:0)
[08:07:25.969] Timer tick, asking Buildbucket for the build status
[08:07:26.915] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:26.915] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:53:0) after 1m0s
[08:08:26.920] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:53:0)
[08:08:26.920] Timer tick, asking Buildbucket for the build status
[08:08:27.522] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:27.522] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:54:0) after 1m0s
[08:09:27.544] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:54:0)
[08:09:27.544] Timer tick, asking Buildbucket for the build status
[08:09:27.835] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:27.835] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:55:0) after 1m0s
[08:10:27.865] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:55:0)
[08:10:27.865] Timer tick, asking Buildbucket for the build status
[08:10:27.954] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:27.954] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:56:0) after 1m0s
[08:11:27.937] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:56:0)
[08:11:27.937] Timer tick, asking Buildbucket for the build status
[08:11:28.392] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:28.392] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:57:0) after 1m0s
[08:12:28.391] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:57:0)
[08:12:28.391] Timer tick, asking Buildbucket for the build status
[08:12:28.997] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:28.997] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:58:0) after 1m0s
[08:13:29.034] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:58:0)
[08:13:29.034] Timer tick, asking Buildbucket for the build status
[08:13:29.294] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:29.294] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:59:0) after 1m0s
[08:14:29.303] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:59:0)
[08:14:29.303] Timer tick, asking Buildbucket for the build status
[08:14:29.561] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:29.561] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:60:0) after 1m0s
[08:15:29.582] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:60:0)
[08:15:29.582] Timer tick, asking Buildbucket for the build status
[08:15:29.943] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:29.943] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:61:0) after 1m0s
[08:16:29.970] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:61:0)
[08:16:29.970] Timer tick, asking Buildbucket for the build status
[08:16:30.083] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:30.083] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:62:0) after 1m0s
[08:17:30.106] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:62:0)
[08:17:30.106] Timer tick, asking Buildbucket for the build status
[08:17:31.144] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:31.144] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:63:0) after 1m0s
[08:18:31.191] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:63:0)
[08:18:31.191] Timer tick, asking Buildbucket for the build status
[08:18:32.038] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:32.038] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:64:0) after 1m0s
[08:19:32.080] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:64:0)
[08:19:32.080] Timer tick, asking Buildbucket for the build status
[08:19:33.090] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:33.090] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:65:0) after 1m0s
[08:20:33.095] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:65:0)
[08:20:33.095] Timer tick, asking Buildbucket for the build status
[08:20:33.333] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:33.333] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:66:0) after 1m0s
[08:21:33.377] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:66:0)
[08:21:33.377] Timer tick, asking Buildbucket for the build status
[08:21:34.069] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:34.069] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:67:0) after 1m0s
[08:22:34.133] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:67:0)
[08:22:34.133] Timer tick, asking Buildbucket for the build status
[08:22:34.622] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:34.622] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:68:0) after 1m0s
[08:23:34.652] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:68:0)
[08:23:34.652] Timer tick, asking Buildbucket for the build status
[08:23:34.959] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:34.959] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:69:0) after 1m0s
[08:24:34.979] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:69:0)
[08:24:34.979] Timer tick, asking Buildbucket for the build status
[08:24:35.163] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:35.163] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:70:0) after 1m0s
[08:25:35.190] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:70:0)
[08:25:35.190] Timer tick, asking Buildbucket for the build status
[08:25:35.282] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:35.282] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:71:0) after 1m0s
[08:26:35.304] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:71:0)
[08:26:35.304] Timer tick, asking Buildbucket for the build status
[08:26:36.314] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:36.314] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:72:0) after 1m0s
[08:27:36.328] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:72:0)
[08:27:36.328] Timer tick, asking Buildbucket for the build status
[08:27:36.582] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:36.582] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:73:0) after 1m0s
[08:28:36.602] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:73:0)
[08:28:36.602] Timer tick, asking Buildbucket for the build status
[08:28:36.989] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:36.989] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:74:0) after 1m0s
[08:29:37.021] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:74:0)
[08:29:37.021] Timer tick, asking Buildbucket for the build status
[08:29:37.155] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:37.155] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:75:0) after 1m0s
[08:30:37.222] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:75:0)
[08:30:37.222] Timer tick, asking Buildbucket for the build status
[08:30:38.668] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:38.668] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:76:0) after 1m0s
[08:31:38.679] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:76:0)
[08:31:38.679] Timer tick, asking Buildbucket for the build status
[08:31:38.992] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:38.992] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:77:0) after 1m0s
[08:32:39.030] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:77:0)
[08:32:39.030] Timer tick, asking Buildbucket for the build status
[08:32:39.275] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:39.275] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:78:0) after 1m0s
[08:33:39.297] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:78:0)
[08:33:39.297] Timer tick, asking Buildbucket for the build status
[08:33:39.918] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:39.918] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:79:0) after 1m0s
[08:34:39.947] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:79:0)
[08:34:39.947] Timer tick, asking Buildbucket for the build status
[08:34:40.275] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:40.275] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:80:0) after 1m0s
[08:35:40.289] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:80:0)
[08:35:40.289] Timer tick, asking Buildbucket for the build status
[08:35:40.523] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:40.523] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:81:0) after 1m0s
[08:36:40.568] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:81:0)
[08:36:40.568] Timer tick, asking Buildbucket for the build status
[08:36:40.769] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:40.769] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:82:0) after 1m0s
[08:37:40.784] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:82:0)
[08:37:40.784] Timer tick, asking Buildbucket for the build status
[08:37:41.119] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:41.119] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:83:0) after 1m0s
[08:38:41.153] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:83:0)
[08:38:41.153] Timer tick, asking Buildbucket for the build status
[08:38:41.492] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:41.492] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:84:0) after 1m0s
[08:39:41.542] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:84:0)
[08:39:41.542] Timer tick, asking Buildbucket for the build status
[08:39:44.348] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:44.348] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:85:0) after 1m0s
[08:40:44.375] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:85:0)
[08:40:44.375] Timer tick, asking Buildbucket for the build status
[08:40:45.564] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:45.564] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:86:0) after 1m0s
[08:41:45.539] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:86:0)
[08:41:45.539] Timer tick, asking Buildbucket for the build status
[08:41:45.852] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:45.852] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:87:0) after 1m0s
[08:42:45.896] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:87:0)
[08:42:45.896] Timer tick, asking Buildbucket for the build status
[08:42:46.762] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:46.762] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:88:0) after 1m0s
[08:43:46.794] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:88:0)
[08:43:46.794] Timer tick, asking Buildbucket for the build status
[08:43:49.319] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:49.319] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:89:0) after 1m0s
[08:44:49.349] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:89:0)
[08:44:49.349] Timer tick, asking Buildbucket for the build status
[08:44:49.518] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:49.518] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:90:0) after 1m0s
[08:45:49.543] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:90:0)
[08:45:49.543] Timer tick, asking Buildbucket for the build status
[08:45:51.498] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:51.498] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:91:0) after 1m0s
[08:46:51.548] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:91:0)
[08:46:51.548] Timer tick, asking Buildbucket for the build status
[08:46:51.987] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:51.987] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:92:0) after 1m0s
[08:47:52.018] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:92:0)
[08:47:52.018] Timer tick, asking Buildbucket for the build status
[08:47:52.389] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:52.389] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:93:0) after 1m0s
[08:48:52.418] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:93:0)
[08:48:52.418] Timer tick, asking Buildbucket for the build status
[08:48:53.411] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:53.411] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:94:0) after 1m0s
[08:49:53.406] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:94:0)
[08:49:53.406] Timer tick, asking Buildbucket for the build status
[08:49:54.304] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:54.304] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:95:0) after 1m0s
[08:50:54.331] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:95:0)
[08:50:54.331] Timer tick, asking Buildbucket for the build status
[08:50:54.487] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:54.487] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:96:0) after 1m0s
[08:51:54.498] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:96:0)
[08:51:54.498] Timer tick, asking Buildbucket for the build status
[08:51:54.659] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:54.659] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:97:0) after 1m0s
[08:52:54.679] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:97:0)
[08:52:54.679] Timer tick, asking Buildbucket for the build status
[08:52:55.029] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:55.029] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:98:0) after 1m0s
[08:53:55.018] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:98:0)
[08:53:55.018] Timer tick, asking Buildbucket for the build status
[08:53:55.798] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:55.798] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:99:0) after 1m0s
[08:54:55.828] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:99:0)
[08:54:55.828] Timer tick, asking Buildbucket for the build status
[08:54:56.330] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:56.331] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:100:0) after 1m0s
[08:55:56.431] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:100:0)
[08:55:56.431] Timer tick, asking Buildbucket for the build status
[08:55:58.485] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:58.485] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:101:0) after 1m0s
[08:56:58.504] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:101:0)
[08:56:58.504] Timer tick, asking Buildbucket for the build status
[08:56:58.629] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:58.629] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:102:0) after 1m0s
[08:57:58.664] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:102:0)
[08:57:58.664] Timer tick, asking Buildbucket for the build status
[08:57:59.252] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:59.252] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:103:0) after 1m0s
[08:58:59.531] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:103:0)
[08:58:59.531] Timer tick, asking Buildbucket for the build status
[08:59:00.187] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:00.187] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:104:0) after 1m0s
[09:00:00.307] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:104:0)
[09:00:00.307] Timer tick, asking Buildbucket for the build status
[09:00:00.576] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:00.576] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:105:0) after 1m0s
[09:01:00.561] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:105:0)
[09:01:00.561] Timer tick, asking Buildbucket for the build status
[09:01:00.762] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:00.762] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:106:0) after 1m0s
[09:02:00.749] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:106:0)
[09:02:00.749] Timer tick, asking Buildbucket for the build status
[09:02:00.962] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:00.962] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:107:0) after 1m0s
[09:03:01.006] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:107:0)
[09:03:01.006] Timer tick, asking Buildbucket for the build status
[09:03:01.552] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:01.552] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:108:0) after 1m0s
[09:04:01.577] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:108:0)
[09:04:01.577] Timer tick, asking Buildbucket for the build status
[09:04:02.795] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:02.795] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:109:0) after 1m0s
[09:05:02.820] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:109:0)
[09:05:02.820] Timer tick, asking Buildbucket for the build status
[09:05:03.706] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:03.706] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:110:0) after 1m0s
[09:06:03.740] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:110:0)
[09:06:03.740] Timer tick, asking Buildbucket for the build status
[09:06:04.457] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:04.458] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:111:0) after 1m0s
[09:07:04.522] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:111:0)
[09:07:04.522] Timer tick, asking Buildbucket for the build status
[09:07:05.090] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:05.090] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:112:0) after 1m0s
[09:08:05.205] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:112:0)
[09:08:05.205] Timer tick, asking Buildbucket for the build status
[09:08:07.397] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:07.397] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:113:0) after 1m0s
[09:09:07.384] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:113:0)
[09:09:07.384] Timer tick, asking Buildbucket for the build status
[09:09:07.837] Build 8912959358239025504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:07.837] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078389421855283264:114:0) after 1m0s
[09:09:39.855] Received PubSub notification, asking Buildbucket for the build status
[09:09:40.354] Build 8912959358239025504: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[09:09:40.354] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1558343378944188",
"created_by": "project:chromium",
"created_ts": "1558336599082280",
"failure_reason": "BUILD_FAILURE",
"id": "8912959358239025504",
"parameters_json": "{\"builder_name\": \"ToTiOS\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"3c128de55a845205b64c6d35edb8a9b1ce33ac12\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Failed ios_web_inttests (iPhone 6s iOS 12.1).\"}, \"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\": \"build284-m9\", \"branch\": \"refs/heads/master\", \"buildername\": \"ToTiOS\", \"buildnumber\": 7796, \"got_angle_revision\": \"70958d15db70251fc1650ae3920acce06998038b\", \"got_dawn_revision\": \"027a8f6464c56c7294a622118d5c1f0fbf9cf58f\", \"got_nacl_revision\": \"ca374f5112fca6b36fc5d73e117a60b82f394993\", \"got_revision\": \"3c128de55a845205b64c6d35edb8a9b1ce33ac12\", \"got_revision_cp\": \"refs/heads/master@{#661280}\", \"got_swarming_client_revision\": \"1b65f4e862045f3ba430a4cbd0643f5b1b66c230\", \"got_v8_revision\": \"c9178292dbb05caf07f5e1cd985f90383fbba6b9\", \"got_v8_revision_cp\": \"refs/heads/7.6.203@{#1}\", \"got_webrtc_revision\": \"519d74a5fcd869478f2bd8cc3a38eca7dfb608e6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27973}\", \"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\": \"3c128de55a845205b64c6d35edb8a9b1ce33ac12\", \"swarm_hashes\": {\"0\": \"2c5ca799261a8c4af55c347d0bcb5f58dbe3d3f8\", \"1\": \"73cc72e9de3284961bb15dc5fdf6f4d4ff54ae8d\", \"10\": \"45bb5bc1d0fae496022391022521963a83c40810\", \"11\": \"f9d5ccb0ac450c3fe7ece2415dc8441bc58ff8d1\", \"12\": \"eec04b2fc61317c091df983cf6d0d3a83a2d2608\", \"13\": \"033cfe13f07b89f7b6183ef5f15cb5b3707b19a0\", \"14\": \"6f841c721a8f571b891d8477fb7e0002cda77da4\", \"15\": \"7e22ce214d80d7cbc7d7bcbf443d330d03369354\", \"16\": \"8b06e330c8057ff6665a0ca90b5c67cfe9f8b813\", \"2\": \"2e8b8f5aba73909c025660ec11cdc315a9a25753\", \"3\": \"be82a09d6bcb1c6a0450971bcb28ba7a91b36d94\", \"4\": \"8cc44fab8294eaa80221b6747c78f4d5e3d53cc7\", \"5\": \"e80c1c1f055f172f70b9731a65e4250239bf0bf1\", \"6\": \"a60c0baaff15ea06224a132dfd0033083285a090\", \"7\": \"ef516378d6880e8e2c1cbb2f32afdd491d62c357\", \"8\": \"ef76aadb9f3e081cecd777259e3551f14a3f2b89\", \"9\": \"6465cda932157da44a22680d2c09b81b03a7eb01\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0c96c161cf7dd2e1c2b922319da904a0c0164c4adceed5a8844a45f1c516ad13_v2\", \"builder_4a4feefdd7e355061708c01720b06a0ca8c49ffc9968a2ebc674541738676b8f_v2\", \"builder_5372538a354f163a20d332d881c6c5d85e35654ac8a57c760866f12033f8a8a5_v2\", \"builder_69caa34b8f39daac28a821cac2619730401d46a6479812cc34796e3bf180418a_v2\", \"builder_ae5debd901c74ee0906916cb62621bceb2a8b657b42c2e2e9e89a0f6a2e5e53b_v2\", \"builder_d42b89eab403033f5a2a8102ec9c436397350cedb439d5e09b11874a5784adb4_v2\", \"git\", \"goma_v2\", \"vpython\", \"xcode_ios_10b61\"], \"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\": [\"build284-m9\"], \"mac_model\": [\"MacPro6,1\"], \"machine_type\": [\"n1-standard-16\"], \"os\": [\"Mac\", \"Mac-10.13\", \"Mac-10.13.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.10\"], \"server_version\": [\"4302-e8210c4\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-golo\", \"us-golo-9\"]}}, \"ui\": {\"info\": \"Failed ios_web_inttests (iPhone 6s iOS 12.1).\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1558336738443806",
"status": "COMPLETED",
"status_changed_ts": "1558336738896665",
"tags": [
"build_address:luci.chromium.ci/ToTiOS/7796",
"builder:ToTiOS",
"buildset:commit/git/3c128de55a845205b64c6d35edb8a9b1ce33ac12",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3c128de55a845205b64c6d35edb8a9b1ce33ac12",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078389421855283264",
"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/8912959358239025504/+/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:44eceb0e25a0e510",
"user_agent:luci-scheduler"
],
"updated_ts": "1558343379528674",
"url": "https://ci.chromium.org/b/8912959358239025504",
"utcnow_ts": "1558343380344037"
}
}
[09:09:40.354] Invocation finished in 1h53m4.103817836s with status FAILED