[09:09:34.780] New invocation is queued and will start shortly
[09:09:36.036] Starting the invocation (attempt 1)
[09:09:36.073] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:09:36.073] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:09:36.073] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9075302017209149600",
"parameters_json": "{\"builder_name\":\"ToTiOSDevice\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"06d2dd7a8933b41545a7c26349c802f570563fd5\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:ToTiOSDevice",
"scheduler_invocation_id:9075302017209149600",
"scheduler_job_id:chromium/ToTiOSDevice",
"user_agent:luci-scheduler",
"buildset:commit/git/06d2dd7a8933b41545a7c26349c802f570563fd5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/06d2dd7a8933b41545a7c26349c802f570563fd5",
"gitiles_ref:refs/heads/master"
]
}
[09:09:38.602] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1561280976777417",
"id": "8909871954452957936",
"parameters_json": "{\"builder_name\": \"ToTiOSDevice\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"06d2dd7a8933b41545a7c26349c802f570563fd5\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1561280977733486",
"tags": [
"build_address:luci.chromium.ci/ToTiOSDevice/12714",
"builder:ToTiOSDevice",
"buildset:commit/git/06d2dd7a8933b41545a7c26349c802f570563fd5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/06d2dd7a8933b41545a7c26349c802f570563fd5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9075302017209149600",
"scheduler_job_id:chromium/ToTiOSDevice",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8909871954452957936/+/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": "1561280977733582",
"url": "https://ci.chromium.org/b/8909871954452957936",
"utcnow_ts": "1561280978565885"
}
}
[09:09:38.602] Task URL: https://ci.chromium.org/b/8909871954452957936
[09:09:38.602] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:2:0) after 1m0s
[09:10:38.669] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:2:0)
[09:10:38.669] Timer tick, asking Buildbucket for the build status
[09:10:38.963] Build 8909871954452957936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:10:38.963] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:3:0) after 1m0s
[09:11:38.981] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:3:0)
[09:11:38.981] Timer tick, asking Buildbucket for the build status
[09:11:39.662] Build 8909871954452957936: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:11:39.662] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:4:0) after 1m0s
[09:11:51.151] Received PubSub notification, asking Buildbucket for the build status
[09:11:51.283] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:39.645] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:4:0)
[09:12:39.645] Timer tick, asking Buildbucket for the build status
[09:12:40.385] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:40.385] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:6:0) after 1m0s
[09:13:40.397] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:6:0)
[09:13:40.397] Timer tick, asking Buildbucket for the build status
[09:13:41.406] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:41.406] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:7:0) after 1m0s
[09:14:41.444] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:7:0)
[09:14:41.444] Timer tick, asking Buildbucket for the build status
[09:14:41.810] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:41.810] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:8:0) after 1m0s
[09:15:41.836] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:8:0)
[09:15:41.836] Timer tick, asking Buildbucket for the build status
[09:15:42.318] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:42.318] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:9:0) after 1m0s
[09:16:42.377] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:9:0)
[09:16:42.377] Timer tick, asking Buildbucket for the build status
[09:16:42.815] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:42.815] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:10:0) after 1m0s
[09:17:42.881] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:10:0)
[09:17:42.881] Timer tick, asking Buildbucket for the build status
[09:17:45.665] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:45.665] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:11:0) after 1m0s
[09:18:45.684] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:11:0)
[09:18:45.684] Timer tick, asking Buildbucket for the build status
[09:18:46.052] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:46.052] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:12:0) after 1m0s
[09:19:46.087] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:12:0)
[09:19:46.087] Timer tick, asking Buildbucket for the build status
[09:19:46.239] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:46.239] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:13:0) after 1m0s
[09:20:46.256] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:13:0)
[09:20:46.256] Timer tick, asking Buildbucket for the build status
[09:20:46.406] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:46.406] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:14:0) after 1m0s
[09:21:46.473] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:14:0)
[09:21:46.473] Timer tick, asking Buildbucket for the build status
[09:21:47.432] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:47.432] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:15:0) after 1m0s
[09:22:47.446] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:15:0)
[09:22:47.447] Timer tick, asking Buildbucket for the build status
[09:22:47.591] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:47.591] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:16:0) after 1m0s
[09:23:47.634] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:16:0)
[09:23:47.634] Timer tick, asking Buildbucket for the build status
[09:23:48.548] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:48.548] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:17:0) after 1m0s
[09:24:48.572] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:17:0)
[09:24:48.572] Timer tick, asking Buildbucket for the build status
[09:24:49.294] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:49.294] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:18:0) after 1m0s
[09:25:49.404] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:18:0)
[09:25:49.404] Timer tick, asking Buildbucket for the build status
[09:25:49.592] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:49.592] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:19:0) after 1m0s
[09:26:49.615] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:19:0)
[09:26:49.615] Timer tick, asking Buildbucket for the build status
[09:26:50.883] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:50.883] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:20:0) after 1m0s
[09:27:51.004] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:20:0)
[09:27:51.004] Timer tick, asking Buildbucket for the build status
[09:27:53.367] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:53.367] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:21:0) after 1m0s
[09:28:53.393] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:21:0)
[09:28:53.393] Timer tick, asking Buildbucket for the build status
[09:28:53.590] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:53.590] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:22:0) after 1m0s
[09:29:53.619] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:22:0)
[09:29:53.619] Timer tick, asking Buildbucket for the build status
[09:29:53.918] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:53.918] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:23:0) after 1m0s
[09:30:53.942] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:23:0)
[09:30:53.942] Timer tick, asking Buildbucket for the build status
[09:30:54.843] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:54.843] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:24:0) after 1m0s
[09:31:54.916] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:24:0)
[09:31:54.917] Timer tick, asking Buildbucket for the build status
[09:31:57.199] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:57.199] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:25:0) after 1m0s
[09:32:57.225] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:25:0)
[09:32:57.225] Timer tick, asking Buildbucket for the build status
[09:32:59.842] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:59.842] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:26:0) after 1m0s
[09:34:00.384] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:26:0)
[09:34:00.384] Timer tick, asking Buildbucket for the build status
[09:34:00.681] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:00.681] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:27:0) after 1m0s
[09:35:00.672] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:27:0)
[09:35:00.672] Timer tick, asking Buildbucket for the build status
[09:35:02.173] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:02.173] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:28:0) after 1m0s
[09:36:02.349] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:28:0)
[09:36:02.349] Timer tick, asking Buildbucket for the build status
[09:36:04.914] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:04.915] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:29:0) after 1m0s
[09:37:04.934] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:29:0)
[09:37:04.934] Timer tick, asking Buildbucket for the build status
[09:37:06.629] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:06.629] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:30:0) after 1m0s
[09:38:06.780] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:30:0)
[09:38:06.780] Timer tick, asking Buildbucket for the build status
[09:38:08.434] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:08.434] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:31:0) after 1m0s
[09:39:08.522] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:31:0)
[09:39:08.522] Timer tick, asking Buildbucket for the build status
[09:39:08.888] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:08.888] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:32:0) after 1m0s
[09:40:08.931] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:32:0)
[09:40:08.931] Timer tick, asking Buildbucket for the build status
[09:40:09.784] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:09.784] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:33:0) after 1m0s
[09:41:09.806] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:33:0)
[09:41:09.806] Timer tick, asking Buildbucket for the build status
[09:41:10.026] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:10.026] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:34:0) after 1m0s
[09:42:10.049] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:34:0)
[09:42:10.049] Timer tick, asking Buildbucket for the build status
[09:42:12.735] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:12.735] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:35:0) after 1m0s
[09:43:12.752] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:35:0)
[09:43:12.752] Timer tick, asking Buildbucket for the build status
[09:43:12.926] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:12.926] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:36:0) after 1m0s
[09:44:12.944] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:36:0)
[09:44:12.944] Timer tick, asking Buildbucket for the build status
[09:44:13.687] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:13.687] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:37:0) after 1m0s
[09:45:13.744] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:37:0)
[09:45:13.744] Timer tick, asking Buildbucket for the build status
[09:45:14.150] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:14.150] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:38:0) after 1m0s
[09:46:14.177] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:38:0)
[09:46:14.178] Timer tick, asking Buildbucket for the build status
[09:46:14.585] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:14.585] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:39:0) after 1m0s
[09:47:14.861] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:39:0)
[09:47:14.861] Timer tick, asking Buildbucket for the build status
[09:47:15.244] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:15.244] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:40:0) after 1m0s
[09:48:15.337] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:40:0)
[09:48:15.337] Timer tick, asking Buildbucket for the build status
[09:48:15.720] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:15.720] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:41:0) after 1m0s
[09:49:15.867] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:41:0)
[09:49:15.867] Timer tick, asking Buildbucket for the build status
[09:49:15.976] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:15.976] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:42:0) after 1m0s
[09:50:16.038] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:42:0)
[09:50:16.038] Timer tick, asking Buildbucket for the build status
[09:50:16.832] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:16.832] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:43:0) after 1m0s
[09:51:16.855] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:43:0)
[09:51:16.855] Timer tick, asking Buildbucket for the build status
[09:51:17.003] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:17.003] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:44:0) after 1m0s
[09:52:17.049] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:44:0)
[09:52:17.049] Timer tick, asking Buildbucket for the build status
[09:52:17.327] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:17.327] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:45:0) after 1m0s
[09:53:17.345] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:45:0)
[09:53:17.345] Timer tick, asking Buildbucket for the build status
[09:53:18.179] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:18.179] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:46:0) after 1m0s
[09:54:18.209] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:46:0)
[09:54:18.209] Timer tick, asking Buildbucket for the build status
[09:54:19.472] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:19.472] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:47:0) after 1m0s
[09:55:19.594] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:47:0)
[09:55:19.594] Timer tick, asking Buildbucket for the build status
[09:55:19.767] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:19.767] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:48:0) after 1m0s
[09:56:19.786] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:48:0)
[09:56:19.786] Timer tick, asking Buildbucket for the build status
[09:56:19.919] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:19.919] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:49:0) after 1m0s
[09:57:19.936] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:49:0)
[09:57:19.936] Timer tick, asking Buildbucket for the build status
[09:57:20.523] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:20.523] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:50:0) after 1m0s
[09:58:20.547] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:50:0)
[09:58:20.547] Timer tick, asking Buildbucket for the build status
[09:58:20.697] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:20.697] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:51:0) after 1m0s
[09:59:20.722] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:51:0)
[09:59:20.722] Timer tick, asking Buildbucket for the build status
[09:59:20.929] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:20.929] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:52:0) after 1m0s
[10:00:20.965] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:52:0)
[10:00:20.965] Timer tick, asking Buildbucket for the build status
[10:00:21.222] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:21.223] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:53:0) after 1m0s
[10:01:21.263] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:53:0)
[10:01:21.263] Timer tick, asking Buildbucket for the build status
[10:01:21.424] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:21.424] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:54:0) after 1m0s
[10:02:21.486] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:54:0)
[10:02:21.486] Timer tick, asking Buildbucket for the build status
[10:02:22.246] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:22.246] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:55:0) after 1m0s
[10:03:22.348] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:55:0)
[10:03:22.348] Timer tick, asking Buildbucket for the build status
[10:03:22.917] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:22.917] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:56:0) after 1m0s
[10:04:23.052] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:56:0)
[10:04:23.052] Timer tick, asking Buildbucket for the build status
[10:04:23.250] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:23.250] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:57:0) after 1m0s
[10:05:23.267] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:57:0)
[10:05:23.267] Timer tick, asking Buildbucket for the build status
[10:05:23.763] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:23.763] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:58:0) after 1m0s
[10:06:23.923] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:58:0)
[10:06:23.923] Timer tick, asking Buildbucket for the build status
[10:06:25.085] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:25.085] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:59:0) after 1m0s
[10:07:25.257] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:59:0)
[10:07:25.257] Timer tick, asking Buildbucket for the build status
[10:07:25.587] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:25.587] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:60:0) after 1m0s
[10:08:26.056] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:60:0)
[10:08:26.056] Timer tick, asking Buildbucket for the build status
[10:08:26.249] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:26.249] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:61:0) after 1m0s
[10:09:26.295] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:61:0)
[10:09:26.295] Timer tick, asking Buildbucket for the build status
[10:09:27.119] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:27.119] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:62:0) after 1m0s
[10:10:27.179] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:62:0)
[10:10:27.179] Timer tick, asking Buildbucket for the build status
[10:10:28.479] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:28.479] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:63:0) after 1m0s
[10:11:28.463] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:63:0)
[10:11:28.463] Timer tick, asking Buildbucket for the build status
[10:11:30.836] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:30.836] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:64:0) after 1m0s
[10:12:30.850] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:64:0)
[10:12:30.850] Timer tick, asking Buildbucket for the build status
[10:12:31.005] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:31.005] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:65:0) after 1m0s
[10:13:31.054] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:65:0)
[10:13:31.054] Timer tick, asking Buildbucket for the build status
[10:13:32.767] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:32.767] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:66:0) after 1m0s
[10:14:32.788] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:66:0)
[10:14:32.788] Timer tick, asking Buildbucket for the build status
[10:14:33.012] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:33.012] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:67:0) after 1m0s
[10:15:33.033] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:67:0)
[10:15:33.033] Timer tick, asking Buildbucket for the build status
[10:15:33.544] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:33.544] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:68:0) after 1m0s
[10:16:33.577] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:68:0)
[10:16:33.577] Timer tick, asking Buildbucket for the build status
[10:16:33.858] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:33.858] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:69:0) after 1m0s
[10:17:33.897] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:69:0)
[10:17:33.897] Timer tick, asking Buildbucket for the build status
[10:17:34.228] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:34.228] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:70:0) after 1m0s
[10:18:34.286] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:70:0)
[10:18:34.286] Timer tick, asking Buildbucket for the build status
[10:18:35.554] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:35.554] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:71:0) after 1m0s
[10:19:35.646] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:71:0)
[10:19:35.646] Timer tick, asking Buildbucket for the build status
[10:19:35.880] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:35.880] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:72:0) after 1m0s
[10:20:35.979] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:72:0)
[10:20:35.979] Timer tick, asking Buildbucket for the build status
[10:20:37.427] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:37.427] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:73:0) after 1m0s
[10:21:37.447] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:73:0)
[10:21:37.447] Timer tick, asking Buildbucket for the build status
[10:21:38.629] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:38.629] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:74:0) after 1m0s
[10:22:38.688] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:74:0)
[10:22:38.688] Timer tick, asking Buildbucket for the build status
[10:22:39.293] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:39.293] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:75:0) after 1m0s
[10:23:39.359] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:75:0)
[10:23:39.359] Timer tick, asking Buildbucket for the build status
[10:23:39.652] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:39.652] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:76:0) after 1m0s
[10:24:39.674] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:76:0)
[10:24:39.674] Timer tick, asking Buildbucket for the build status
[10:24:39.869] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:39.869] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:77:0) after 1m0s
[10:25:40.036] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:77:0)
[10:25:40.036] Timer tick, asking Buildbucket for the build status
[10:25:40.381] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:40.381] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:78:0) after 1m0s
[10:26:40.406] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:78:0)
[10:26:40.406] Timer tick, asking Buildbucket for the build status
[10:26:40.724] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:40.724] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:79:0) after 1m0s
[10:27:40.756] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:79:0)
[10:27:40.756] Timer tick, asking Buildbucket for the build status
[10:27:40.924] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:40.924] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:80:0) after 1m0s
[10:28:41.043] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:80:0)
[10:28:41.043] Timer tick, asking Buildbucket for the build status
[10:28:41.845] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:41.845] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:81:0) after 1m0s
[10:29:41.879] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:81:0)
[10:29:41.879] Timer tick, asking Buildbucket for the build status
[10:29:42.039] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:42.039] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:82:0) after 1m0s
[10:30:42.058] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:82:0)
[10:30:42.058] Timer tick, asking Buildbucket for the build status
[10:30:42.283] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:42.283] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:83:0) after 1m0s
[10:31:42.338] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:83:0)
[10:31:42.338] Timer tick, asking Buildbucket for the build status
[10:31:42.528] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:42.528] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:84:0) after 1m0s
[10:32:42.563] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:84:0)
[10:32:42.563] Timer tick, asking Buildbucket for the build status
[10:32:43.110] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:43.110] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:85:0) after 1m0s
[10:33:43.142] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:85:0)
[10:33:43.142] Timer tick, asking Buildbucket for the build status
[10:33:44.746] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:44.746] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:86:0) after 1m0s
[10:34:44.762] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:86:0)
[10:34:44.762] Timer tick, asking Buildbucket for the build status
[10:34:44.906] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:44.906] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:87:0) after 1m0s
[10:35:45.069] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:87:0)
[10:35:45.069] Timer tick, asking Buildbucket for the build status
[10:35:45.246] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:45.246] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:88:0) after 1m0s
[10:36:45.556] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:88:0)
[10:36:45.556] Timer tick, asking Buildbucket for the build status
[10:36:45.807] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:45.807] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:89:0) after 1m0s
[10:37:45.830] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:89:0)
[10:37:45.830] Timer tick, asking Buildbucket for the build status
[10:37:46.353] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:46.353] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:90:0) after 1m0s
[10:38:46.691] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:90:0)
[10:38:46.691] Timer tick, asking Buildbucket for the build status
[10:38:46.849] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:46.849] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:91:0) after 1m0s
[10:39:47.058] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:91:0)
[10:39:47.058] Timer tick, asking Buildbucket for the build status
[10:39:47.409] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:47.409] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:92:0) after 1m0s
[10:40:47.573] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:92:0)
[10:40:47.573] Timer tick, asking Buildbucket for the build status
[10:40:48.138] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:48.138] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:93:0) after 1m0s
[10:41:48.188] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:93:0)
[10:41:48.188] Timer tick, asking Buildbucket for the build status
[10:41:48.423] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:48.423] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:94:0) after 1m0s
[10:42:48.543] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:94:0)
[10:42:48.543] Timer tick, asking Buildbucket for the build status
[10:42:48.726] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:48.726] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:95:0) after 1m0s
[10:43:48.926] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:95:0)
[10:43:48.926] Timer tick, asking Buildbucket for the build status
[10:43:49.641] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:49.641] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:96:0) after 1m0s
[10:44:49.761] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:96:0)
[10:44:49.761] Timer tick, asking Buildbucket for the build status
[10:44:50.385] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:50.385] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:97:0) after 1m0s
[10:45:58.087] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:97:0)
[10:45:58.087] Timer tick, asking Buildbucket for the build status
[10:45:58.285] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:58.285] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:98:0) after 1m0s
[10:46:58.659] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:98:0)
[10:46:58.659] Timer tick, asking Buildbucket for the build status
[10:46:59.028] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:59.028] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:99:0) after 1m0s
[10:47:59.242] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:99:0)
[10:47:59.242] Timer tick, asking Buildbucket for the build status
[10:48:00.116] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:00.116] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:100:0) after 1m0s
[10:49:00.250] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:100:0)
[10:49:00.250] Timer tick, asking Buildbucket for the build status
[10:49:00.727] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:00.727] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:101:0) after 1m0s
[10:50:00.701] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:101:0)
[10:50:00.701] Timer tick, asking Buildbucket for the build status
[10:50:04.797] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:04.797] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:102:0) after 1m0s
[10:51:04.996] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:102:0)
[10:51:04.996] Timer tick, asking Buildbucket for the build status
[10:51:05.892] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:05.892] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:103:0) after 1m0s
[10:52:06.059] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:103:0)
[10:52:06.059] Timer tick, asking Buildbucket for the build status
[10:52:06.329] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:06.329] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:104:0) after 1m0s
[10:53:06.351] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:104:0)
[10:53:06.351] Timer tick, asking Buildbucket for the build status
[10:53:06.649] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:06.649] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:105:0) after 1m0s
[10:54:06.692] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:105:0)
[10:54:06.692] Timer tick, asking Buildbucket for the build status
[10:54:06.846] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:06.846] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:106:0) after 1m0s
[10:55:07.001] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:106:0)
[10:55:07.001] Timer tick, asking Buildbucket for the build status
[10:55:07.365] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:07.365] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:107:0) after 1m0s
[10:56:07.615] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:107:0)
[10:56:07.615] Timer tick, asking Buildbucket for the build status
[10:56:08.102] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:08.102] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:108:0) after 1m0s
[10:57:08.180] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:108:0)
[10:57:08.181] Timer tick, asking Buildbucket for the build status
[10:57:08.500] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:08.500] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:109:0) after 1m0s
[10:58:08.557] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:109:0)
[10:58:08.557] Timer tick, asking Buildbucket for the build status
[10:58:09.285] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:09.286] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:110:0) after 1m0s
[10:59:09.318] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:110:0)
[10:59:09.318] Timer tick, asking Buildbucket for the build status
[10:59:09.820] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:09.820] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:111:0) after 1m0s
[11:00:09.851] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:111:0)
[11:00:09.851] Timer tick, asking Buildbucket for the build status
[11:00:10.145] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:10.145] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:112:0) after 1m0s
[11:01:10.280] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:112:0)
[11:01:10.281] Timer tick, asking Buildbucket for the build status
[11:01:10.696] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:10.696] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:113:0) after 1m0s
[11:02:10.743] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:113:0)
[11:02:10.743] Timer tick, asking Buildbucket for the build status
[11:02:11.004] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:11.004] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:114:0) after 1m0s
[11:03:11.038] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:114:0)
[11:03:11.038] Timer tick, asking Buildbucket for the build status
[11:03:11.677] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:11.677] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:115:0) after 1m0s
[11:04:11.880] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:115:0)
[11:04:11.880] Timer tick, asking Buildbucket for the build status
[11:04:13.471] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:13.471] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:116:0) after 1m0s
[11:05:13.552] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:116:0)
[11:05:13.552] Timer tick, asking Buildbucket for the build status
[11:05:13.713] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:13.713] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:117:0) after 1m0s
[11:06:13.757] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:117:0)
[11:06:13.757] Timer tick, asking Buildbucket for the build status
[11:06:13.937] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:13.937] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:118:0) after 1m0s
[11:07:14.207] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:118:0)
[11:07:14.207] Timer tick, asking Buildbucket for the build status
[11:07:14.424] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:14.424] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:119:0) after 1m0s
[11:08:14.549] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:119:0)
[11:08:14.549] Timer tick, asking Buildbucket for the build status
[11:08:14.933] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:14.933] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:120:0) after 1m0s
[11:09:15.152] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:120:0)
[11:09:15.152] Timer tick, asking Buildbucket for the build status
[11:09:15.881] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:15.881] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:121:0) after 1m0s
[11:10:15.970] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:121:0)
[11:10:15.970] Timer tick, asking Buildbucket for the build status
[11:10:16.229] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:16.229] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:122:0) after 1m0s
[11:11:16.249] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:122:0)
[11:11:16.249] Timer tick, asking Buildbucket for the build status
[11:11:16.847] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:16.847] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:123:0) after 1m0s
[11:12:16.895] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:123:0)
[11:12:16.895] Timer tick, asking Buildbucket for the build status
[11:12:17.168] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:17.168] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:124:0) after 1m0s
[11:13:17.257] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:124:0)
[11:13:17.257] Timer tick, asking Buildbucket for the build status
[11:13:18.256] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:18.256] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:125:0) after 1m0s
[11:14:18.580] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:125:0)
[11:14:18.580] Timer tick, asking Buildbucket for the build status
[11:14:19.121] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:19.121] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:126:0) after 1m0s
[11:15:19.269] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:126:0)
[11:15:19.269] Timer tick, asking Buildbucket for the build status
[11:15:19.470] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:19.470] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:127:0) after 1m0s
[11:16:19.496] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:127:0)
[11:16:19.496] Timer tick, asking Buildbucket for the build status
[11:16:19.765] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:19.765] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:128:0) after 1m0s
[11:17:19.789] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:128:0)
[11:17:19.789] Timer tick, asking Buildbucket for the build status
[11:17:20.003] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:20.003] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:129:0) after 1m0s
[11:18:20.075] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:129:0)
[11:18:20.075] Timer tick, asking Buildbucket for the build status
[11:18:20.634] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:20.634] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:130:0) after 1m0s
[11:19:20.671] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:130:0)
[11:19:20.671] Timer tick, asking Buildbucket for the build status
[11:19:21.335] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:21.335] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:131:0) after 1m0s
[11:20:21.369] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:131:0)
[11:20:21.369] Timer tick, asking Buildbucket for the build status
[11:20:21.525] Build 8909871954452957936: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:21.525] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:9075302017209149600:132:0) after 1m0s
[11:20:22.675] Received PubSub notification, asking Buildbucket for the build status
[11:20:22.776] Build 8909871954452957936: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:20:22.776] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1561288821204357",
"created_by": "project:chromium",
"created_ts": "1561280976777417",
"id": "8909871954452957936",
"parameters_json": "{\"builder_name\": \"ToTiOSDevice\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"06d2dd7a8933b41545a7c26349c802f570563fd5\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"build282-m9\", \"branch\": \"refs/heads/master\", \"buildername\": \"ToTiOSDevice\", \"buildnumber\": 12714, \"got_angle_revision\": \"dfd760055152076509c59b23e1604d83a7435688\", \"got_dawn_revision\": \"4ec2c1060e85678809c73bd9ee81120567b23dd8\", \"got_nacl_revision\": \"c4cf6f0621b94cca4053b19fa01ef329f5663880\", \"got_revision\": \"06d2dd7a8933b41545a7c26349c802f570563fd5\", \"got_revision_cp\": \"refs/heads/master@{#671553}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"29692ef0ad9619997feb40e26d66d35c49a0111e\", \"got_v8_revision_cp\": \"refs/heads/7.7.133@{#1}\", \"got_webrtc_revision\": \"abf7eba8658c6facda98210399bf88d0c5f72036\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28339}\", \"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\": \"06d2dd7a8933b41545a7c26349c802f570563fd5\", \"swarm_hashes\": {\"0\": \"0159884896e8a6e077f9e676d12ccee731a3d56a\", \"1\": \"10be4936c7e60106fa6eddba74c4dd18e48aca12\", \"10\": \"ad65c36eb90cab4fe2943ed1fc3b3cb02b673431\", \"11\": \"a014a790150826300c3fe2d8113a0fe06d267748\", \"12\": \"62f58fad4381a149803f69845aea4cb007b1261e\", \"13\": \"2cf8f10379842e6a16ee7b5178c4635ca6674bdf\", \"14\": \"b2d6d629f9682213955e58f184c98d97f199830e\", \"15\": \"c1dbf07a9681e98e0f01b2360339311afc2afbea\", \"16\": \"3572fc5e5c9066166f29b097ff91f1c5c06d0de5\", \"2\": \"b50a1b5135caac24d22fe74f5ed0ab1427868d4a\", \"3\": \"23d5e0691e9c6fc5db87beb78bc3535ddd12f7b8\", \"4\": \"41a09f5714b0433932b879335296552e6bfcd5ef\", \"5\": \"d78af39ace2dda5720cce9f566218a1e870bb6e4\", \"6\": \"f9341e7ada66b7896c312f8c17d8a7de7566ad4c\", \"7\": \"218bfd3d02cd058c7361a0e0f5a9365305011bb5\", \"8\": \"4d9735328bc426a3ff2e1b75e6a0ab7a185551a7\", \"9\": \"222710ad0addf32fb605250df9395b51e77733a9\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0c96c161cf7dd2e1c2b922319da904a0c0164c4adceed5a8844a45f1c516ad13_v2\", \"builder_4a4feefdd7e355061708c01720b06a0ca8c49ffc9968a2ebc674541738676b8f_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\": [\"build282-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\": [\"4339-6285c57\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-atl\", \"us-atl-golo\", \"us-atl-golo-m9\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1561281108642679",
"status": "COMPLETED",
"status_changed_ts": "1561288822174212",
"tags": [
"build_address:luci.chromium.ci/ToTiOSDevice/12714",
"builder:ToTiOSDevice",
"buildset:commit/git/06d2dd7a8933b41545a7c26349c802f570563fd5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/06d2dd7a8933b41545a7c26349c802f570563fd5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9075302017209149600",
"scheduler_job_id:chromium/ToTiOSDevice",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8909871954452957936/+/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:459c6aa660bd9110",
"user_agent:luci-scheduler"
],
"updated_ts": "1561288822177530",
"url": "https://ci.chromium.org/b/8909871954452957936",
"utcnow_ts": "1561288822769498"
}
}
[11:20:22.776] Invocation finished in 2h10m48.011001301s with status SUCCEEDED