[12:30:44.253] New invocation is queued and will start shortly
[12:30:45.366] Starting the invocation (attempt 1)
[12:30:45.407] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:30:45.408] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:30:45.408] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9078007270446691552",
"parameters_json": "{\"builder_name\":\"ToTiOS\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"61f5138f81ad66f582700f14dbc9d7fe6e5100ee\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:ToTiOS",
"scheduler_invocation_id:9078007270446691552",
"scheduler_job_id:chromium/ToTiOS",
"user_agent:luci-scheduler",
"buildset:commit/git/61f5138f81ad66f582700f14dbc9d7fe6e5100ee",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/61f5138f81ad66f582700f14dbc9d7fe6e5100ee",
"gitiles_ref:refs/heads/master"
]
}
[12:30:46.644] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1558701045549310",
"id": "8912577208420964080",
"parameters_json": "{\"builder_name\": \"ToTiOS\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"61f5138f81ad66f582700f14dbc9d7fe6e5100ee\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1558701046488212",
"tags": [
"build_address:luci.chromium.ci/ToTiOS/7875",
"builder:ToTiOS",
"buildset:commit/git/61f5138f81ad66f582700f14dbc9d7fe6e5100ee",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/61f5138f81ad66f582700f14dbc9d7fe6e5100ee",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078007270446691552",
"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/8912577208420964080/+/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": "1558701046488312",
"url": "https://ci.chromium.org/b/8912577208420964080",
"utcnow_ts": "1558701046634262"
}
}
[12:30:46.644] Task URL: https://ci.chromium.org/b/8912577208420964080
[12:30:46.644] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:2:0) after 1m0s
[12:31:46.735] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:2:0)
[12:31:46.735] Timer tick, asking Buildbucket for the build status
[12:31:47.011] Build 8912577208420964080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:31:47.011] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:3:0) after 1m0s
[12:32:47.141] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:3:0)
[12:32:47.141] Timer tick, asking Buildbucket for the build status
[12:32:47.461] Build 8912577208420964080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:32:47.461] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:4:0) after 1m0s
[12:33:11.942] Received PubSub notification, asking Buildbucket for the build status
[12:33:12.161] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:49.843] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:4:0)
[12:33:49.843] Timer tick, asking Buildbucket for the build status
[12:33:52.133] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:52.133] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:6:0) after 1m0s
[12:34:52.405] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:6:0)
[12:34:52.405] Timer tick, asking Buildbucket for the build status
[12:34:52.529] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:52.529] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:7:0) after 1m0s
[12:35:53.044] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:7:0)
[12:35:53.044] Timer tick, asking Buildbucket for the build status
[12:35:54.730] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:54.730] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:8:0) after 1m0s
[12:36:54.765] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:8:0)
[12:36:54.765] Timer tick, asking Buildbucket for the build status
[12:36:56.792] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:56.792] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:9:0) after 1m0s
[12:37:56.872] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:9:0)
[12:37:56.872] Timer tick, asking Buildbucket for the build status
[12:37:57.530] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:57.530] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:10:0) after 1m0s
[12:38:57.629] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:10:0)
[12:38:57.629] Timer tick, asking Buildbucket for the build status
[12:38:57.839] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:57.839] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:11:0) after 1m0s
[12:39:57.865] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:11:0)
[12:39:57.865] Timer tick, asking Buildbucket for the build status
[12:39:57.983] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:57.983] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:12:0) after 1m0s
[12:40:58.039] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:12:0)
[12:40:58.039] Timer tick, asking Buildbucket for the build status
[12:40:59.093] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:59.093] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:13:0) after 1m0s
[12:41:59.231] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:13:0)
[12:41:59.231] Timer tick, asking Buildbucket for the build status
[12:41:59.349] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:59.349] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:14:0) after 1m0s
[12:42:59.432] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:14:0)
[12:42:59.432] Timer tick, asking Buildbucket for the build status
[12:43:00.744] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:00.744] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:15:0) after 1m0s
[12:44:00.898] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:15:0)
[12:44:00.898] Timer tick, asking Buildbucket for the build status
[12:44:01.110] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:01.110] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:16:0) after 1m0s
[12:45:01.224] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:16:0)
[12:45:01.224] Timer tick, asking Buildbucket for the build status
[12:45:02.517] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:02.517] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:17:0) after 1m0s
[12:46:02.563] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:17:0)
[12:46:02.563] Timer tick, asking Buildbucket for the build status
[12:46:03.038] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:03.038] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:18:0) after 1m0s
[12:47:03.103] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:18:0)
[12:47:03.103] Timer tick, asking Buildbucket for the build status
[12:47:03.748] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:03.749] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:19:0) after 1m0s
[12:48:03.997] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:19:0)
[12:48:03.997] Timer tick, asking Buildbucket for the build status
[12:48:04.173] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:04.173] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:20:0) after 1m0s
[12:49:04.243] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:20:0)
[12:49:04.243] Timer tick, asking Buildbucket for the build status
[12:49:04.539] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:04.539] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:21:0) after 1m0s
[12:50:04.643] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:21:0)
[12:50:04.643] Timer tick, asking Buildbucket for the build status
[12:50:04.747] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:04.747] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:22:0) after 1m0s
[12:51:04.791] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:22:0)
[12:51:04.791] Timer tick, asking Buildbucket for the build status
[12:51:07.101] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:07.101] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:23:0) after 1m0s
[12:52:45.670] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:23:0)
[12:52:45.670] Timer tick, asking Buildbucket for the build status
[12:52:46.366] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:46.366] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:24:0) after 1m0s
[12:53:46.603] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:24:0)
[12:53:46.603] Timer tick, asking Buildbucket for the build status
[12:53:46.899] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:46.899] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:25:0) after 1m0s
[12:54:47.102] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:25:0)
[12:54:47.102] Timer tick, asking Buildbucket for the build status
[12:54:48.818] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:48.818] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:26:0) after 1m0s
[12:55:49.177] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:26:0)
[12:55:49.177] Timer tick, asking Buildbucket for the build status
[12:55:49.924] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:49.924] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:27:0) after 1m0s
[12:56:50.099] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:27:0)
[12:56:50.099] Timer tick, asking Buildbucket for the build status
[12:56:50.878] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:50.878] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:28:0) after 1m0s
[12:57:51.178] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:28:0)
[12:57:51.178] Timer tick, asking Buildbucket for the build status
[12:57:51.401] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:51.401] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:29:0) after 1m0s
[12:58:51.629] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:29:0)
[12:58:51.629] Timer tick, asking Buildbucket for the build status
[12:58:51.760] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:51.760] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:30:0) after 1m0s
[12:59:51.859] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:30:0)
[12:59:51.859] Timer tick, asking Buildbucket for the build status
[12:59:52.179] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:52.179] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:31:0) after 1m0s
[13:00:52.311] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:31:0)
[13:00:52.311] Timer tick, asking Buildbucket for the build status
[13:00:52.578] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:52.578] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:32:0) after 1m0s
[13:01:52.627] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:32:0)
[13:01:52.627] Timer tick, asking Buildbucket for the build status
[13:01:53.429] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:53.429] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:33:0) after 1m0s
[13:02:53.758] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:33:0)
[13:02:53.758] Timer tick, asking Buildbucket for the build status
[13:02:55.731] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:55.731] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:34:0) after 1m0s
[13:03:55.882] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:34:0)
[13:03:55.882] Timer tick, asking Buildbucket for the build status
[13:03:56.045] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:56.045] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:35:0) after 1m0s
[13:04:56.119] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:35:0)
[13:04:56.119] Timer tick, asking Buildbucket for the build status
[13:04:56.967] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:56.967] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:36:0) after 1m0s
[13:05:57.077] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:36:0)
[13:05:57.077] Timer tick, asking Buildbucket for the build status
[13:05:57.933] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:57.933] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:37:0) after 1m0s
[13:06:57.959] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:37:0)
[13:06:57.959] Timer tick, asking Buildbucket for the build status
[13:06:58.174] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:58.174] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:38:0) after 1m0s
[13:07:58.237] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:38:0)
[13:07:58.237] Timer tick, asking Buildbucket for the build status
[13:07:58.409] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:58.409] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:39:0) after 1m0s
[13:08:58.538] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:39:0)
[13:08:58.538] Timer tick, asking Buildbucket for the build status
[13:08:59.055] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:59.055] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:40:0) after 1m0s
[13:09:59.099] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:40:0)
[13:09:59.099] Timer tick, asking Buildbucket for the build status
[13:09:59.962] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:59.962] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:41:0) after 1m0s
[13:11:00.014] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:41:0)
[13:11:00.014] Timer tick, asking Buildbucket for the build status
[13:11:00.251] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:00.251] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:42:0) after 1m0s
[13:12:00.460] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:42:0)
[13:12:00.460] Timer tick, asking Buildbucket for the build status
[13:12:00.661] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:00.661] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:43:0) after 1m0s
[13:13:00.862] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:43:0)
[13:13:00.862] Timer tick, asking Buildbucket for the build status
[13:13:01.003] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:01.003] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:44:0) after 1m0s
[13:14:01.114] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:44:0)
[13:14:01.114] Timer tick, asking Buildbucket for the build status
[13:14:01.465] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:01.465] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:45:0) after 1m0s
[13:15:01.660] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:45:0)
[13:15:01.660] Timer tick, asking Buildbucket for the build status
[13:15:03.434] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:03.434] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:46:0) after 1m0s
[13:16:03.459] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:46:0)
[13:16:03.459] Timer tick, asking Buildbucket for the build status
[13:16:03.681] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:03.681] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:47:0) after 1m0s
[13:17:03.679] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:47:0)
[13:17:03.679] Timer tick, asking Buildbucket for the build status
[13:17:04.149] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:04.149] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:48:0) after 1m0s
[13:18:04.400] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:48:0)
[13:18:04.400] Timer tick, asking Buildbucket for the build status
[13:18:04.624] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:04.624] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:49:0) after 1m0s
[13:19:04.777] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:49:0)
[13:19:04.777] Timer tick, asking Buildbucket for the build status
[13:19:04.882] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:04.882] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:50:0) after 1m0s
[13:20:04.906] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:50:0)
[13:20:04.906] Timer tick, asking Buildbucket for the build status
[13:20:05.795] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:05.795] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:51:0) after 1m0s
[13:21:06.094] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:51:0)
[13:21:06.095] Timer tick, asking Buildbucket for the build status
[13:21:08.561] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:08.561] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:52:0) after 1m0s
[13:22:08.655] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:52:0)
[13:22:08.655] Timer tick, asking Buildbucket for the build status
[13:22:08.792] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:08.792] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:53:0) after 1m0s
[13:23:08.922] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:53:0)
[13:23:08.922] Timer tick, asking Buildbucket for the build status
[13:23:09.150] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:09.150] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:54:0) after 1m0s
[13:24:09.240] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:54:0)
[13:24:09.240] Timer tick, asking Buildbucket for the build status
[13:24:10.004] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:10.004] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:55:0) after 1m0s
[13:25:10.043] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:55:0)
[13:25:10.043] Timer tick, asking Buildbucket for the build status
[13:25:10.141] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:10.141] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:56:0) after 1m0s
[13:26:10.623] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:56:0)
[13:26:10.623] Timer tick, asking Buildbucket for the build status
[13:26:15.092] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:15.092] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:57:0) after 1m0s
[13:27:15.117] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:57:0)
[13:27:15.117] Timer tick, asking Buildbucket for the build status
[13:27:16.472] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:16.472] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:58:0) after 1m0s
[13:28:16.557] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:58:0)
[13:28:16.557] Timer tick, asking Buildbucket for the build status
[13:28:16.919] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:16.919] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:59:0) after 1m0s
[13:29:17.045] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:59:0)
[13:29:17.045] Timer tick, asking Buildbucket for the build status
[13:29:17.415] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:17.415] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:60:0) after 1m0s
[13:30:17.500] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:60:0)
[13:30:17.500] Timer tick, asking Buildbucket for the build status
[13:30:17.666] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:17.666] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:61:0) after 1m0s
[13:31:17.768] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:61:0)
[13:31:17.768] Timer tick, asking Buildbucket for the build status
[13:31:18.172] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:18.172] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:62:0) after 1m0s
[13:32:18.205] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:62:0)
[13:32:18.206] Timer tick, asking Buildbucket for the build status
[13:32:19.436] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:19.436] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:63:0) after 1m0s
[13:33:19.531] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:63:0)
[13:33:19.531] Timer tick, asking Buildbucket for the build status
[13:33:21.613] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:21.613] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:64:0) after 1m0s
[13:34:21.725] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:64:0)
[13:34:21.725] Timer tick, asking Buildbucket for the build status
[13:34:23.348] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:23.348] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:65:0) after 1m0s
[13:35:23.423] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:65:0)
[13:35:23.423] Timer tick, asking Buildbucket for the build status
[13:35:24.411] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:24.411] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:66:0) after 1m0s
[13:36:24.475] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:66:0)
[13:36:24.475] Timer tick, asking Buildbucket for the build status
[13:36:25.250] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:25.250] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:67:0) after 1m0s
[13:37:25.353] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:67:0)
[13:37:25.353] Timer tick, asking Buildbucket for the build status
[13:37:25.453] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:25.453] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:68:0) after 1m0s
[13:38:25.768] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:68:0)
[13:38:25.768] Timer tick, asking Buildbucket for the build status
[13:38:26.107] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:26.107] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:69:0) after 1m0s
[13:39:26.423] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:69:0)
[13:39:26.423] Timer tick, asking Buildbucket for the build status
[13:39:26.556] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:26.556] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:70:0) after 1m0s
[13:40:26.677] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:70:0)
[13:40:26.677] Timer tick, asking Buildbucket for the build status
[13:40:28.638] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:28.638] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:71:0) after 1m0s
[13:41:28.659] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:71:0)
[13:41:28.659] Timer tick, asking Buildbucket for the build status
[13:41:28.947] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:28.947] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:72:0) after 1m0s
[13:42:28.998] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:72:0)
[13:42:28.998] Timer tick, asking Buildbucket for the build status
[13:42:29.151] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:29.151] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:73:0) after 1m0s
[13:43:29.192] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:73:0)
[13:43:29.192] Timer tick, asking Buildbucket for the build status
[13:43:29.297] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:29.297] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:74:0) after 1m0s
[13:44:29.319] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:74:0)
[13:44:29.319] Timer tick, asking Buildbucket for the build status
[13:44:29.593] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:29.593] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:75:0) after 1m0s
[13:45:29.645] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:75:0)
[13:45:29.645] Timer tick, asking Buildbucket for the build status
[13:45:29.809] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:29.809] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:76:0) after 1m0s
[13:46:29.845] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:76:0)
[13:46:29.845] Timer tick, asking Buildbucket for the build status
[13:46:30.212] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:30.212] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:77:0) after 1m0s
[13:47:30.395] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:77:0)
[13:47:30.395] Timer tick, asking Buildbucket for the build status
[13:47:30.956] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:30.956] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:78:0) after 1m0s
[13:48:31.257] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:78:0)
[13:48:31.257] Timer tick, asking Buildbucket for the build status
[13:48:31.699] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:31.699] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:79:0) after 1m0s
[13:49:31.936] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:79:0)
[13:49:31.936] Timer tick, asking Buildbucket for the build status
[13:49:32.872] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:32.872] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:80:0) after 1m0s
[13:50:32.983] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:80:0)
[13:50:32.983] Timer tick, asking Buildbucket for the build status
[13:50:33.442] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:33.442] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:81:0) after 1m0s
[13:51:33.532] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:81:0)
[13:51:33.532] Timer tick, asking Buildbucket for the build status
[13:51:34.326] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:34.326] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:82:0) after 1m0s
[13:52:34.440] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:82:0)
[13:52:34.440] Timer tick, asking Buildbucket for the build status
[13:52:34.577] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:34.577] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:83:0) after 1m0s
[13:53:34.678] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:83:0)
[13:53:34.678] Timer tick, asking Buildbucket for the build status
[13:53:36.847] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:36.847] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:84:0) after 1m0s
[13:54:36.982] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:84:0)
[13:54:36.982] Timer tick, asking Buildbucket for the build status
[13:54:37.238] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:37.238] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:85:0) after 1m0s
[13:55:37.581] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:85:0)
[13:55:37.581] Timer tick, asking Buildbucket for the build status
[13:55:39.564] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:39.564] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:86:0) after 1m0s
[13:56:39.569] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:86:0)
[13:56:39.569] Timer tick, asking Buildbucket for the build status
[13:56:39.712] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:39.712] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:87:0) after 1m0s
[13:57:39.819] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:87:0)
[13:57:39.819] Timer tick, asking Buildbucket for the build status
[13:57:40.521] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:40.521] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:88:0) after 1m0s
[13:58:40.580] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:88:0)
[13:58:40.580] Timer tick, asking Buildbucket for the build status
[13:58:41.190] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:41.190] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:89:0) after 1m0s
[13:59:41.219] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:89:0)
[13:59:41.219] Timer tick, asking Buildbucket for the build status
[13:59:41.811] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:41.811] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:90:0) after 1m0s
[14:00:41.853] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:90:0)
[14:00:41.853] Timer tick, asking Buildbucket for the build status
[14:00:42.032] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:42.033] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:91:0) after 1m0s
[14:01:42.096] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:91:0)
[14:01:42.096] Timer tick, asking Buildbucket for the build status
[14:01:42.463] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:42.463] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:92:0) after 1m0s
[14:02:42.608] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:92:0)
[14:02:42.608] Timer tick, asking Buildbucket for the build status
[14:02:42.921] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:42.921] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:93:0) after 1m0s
[14:03:43.164] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:93:0)
[14:03:43.164] Timer tick, asking Buildbucket for the build status
[14:03:43.283] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:43.284] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:94:0) after 1m0s
[14:04:43.426] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:94:0)
[14:04:43.426] Timer tick, asking Buildbucket for the build status
[14:04:45.599] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:45.599] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:95:0) after 1m0s
[14:05:45.747] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:95:0)
[14:05:45.747] Timer tick, asking Buildbucket for the build status
[14:05:47.183] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:47.183] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:96:0) after 1m0s
[14:06:47.206] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:96:0)
[14:06:47.206] Timer tick, asking Buildbucket for the build status
[14:06:47.901] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:47.901] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:97:0) after 1m0s
[14:07:47.979] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:97:0)
[14:07:47.979] Timer tick, asking Buildbucket for the build status
[14:07:48.297] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:48.297] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:98:0) after 1m0s
[14:08:48.353] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:98:0)
[14:08:48.353] Timer tick, asking Buildbucket for the build status
[14:08:48.863] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:48.863] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:99:0) after 1m0s
[14:09:48.970] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:99:0)
[14:09:48.970] Timer tick, asking Buildbucket for the build status
[14:09:56.514] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:56.514] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:100:0) after 1m0s
[14:10:56.608] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:100:0)
[14:10:56.608] Timer tick, asking Buildbucket for the build status
[14:10:57.420] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:57.420] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:101:0) after 1m0s
[14:11:57.629] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:101:0)
[14:11:57.629] Timer tick, asking Buildbucket for the build status
[14:11:57.890] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:57.890] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:102:0) after 1m0s
[14:12:57.996] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:102:0)
[14:12:57.996] Timer tick, asking Buildbucket for the build status
[14:12:58.951] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:58.951] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:103:0) after 1m0s
[14:13:59.059] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:103:0)
[14:13:59.059] Timer tick, asking Buildbucket for the build status
[14:13:59.160] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:59.160] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:104:0) after 1m0s
[14:14:59.302] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:104:0)
[14:14:59.302] Timer tick, asking Buildbucket for the build status
[14:14:59.562] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:59.562] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:105:0) after 1m0s
[14:15:59.593] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:105:0)
[14:15:59.593] Timer tick, asking Buildbucket for the build status
[14:16:00.096] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:00.096] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:106:0) after 1m0s
[14:17:00.260] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:106:0)
[14:17:00.260] Timer tick, asking Buildbucket for the build status
[14:17:01.579] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:01.579] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:107:0) after 1m0s
[14:18:01.610] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:107:0)
[14:18:01.611] Timer tick, asking Buildbucket for the build status
[14:18:01.805] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:01.805] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:108:0) after 1m0s
[14:19:01.827] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:108:0)
[14:19:01.827] Timer tick, asking Buildbucket for the build status
[14:19:02.208] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:02.208] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:109:0) after 1m0s
[14:20:02.286] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:109:0)
[14:20:02.286] Timer tick, asking Buildbucket for the build status
[14:20:02.459] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:02.459] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:110:0) after 1m0s
[14:21:02.531] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:110:0)
[14:21:02.531] Timer tick, asking Buildbucket for the build status
[14:21:03.374] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:03.374] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:111:0) after 1m0s
[14:22:03.495] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:111:0)
[14:22:03.495] Timer tick, asking Buildbucket for the build status
[14:22:03.701] Build 8912577208420964080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:03.701] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9078007270446691552:112:0) after 1m0s
[14:22:34.173] Received PubSub notification, asking Buildbucket for the build status
[14:22:34.407] Build 8912577208420964080: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[14:22:34.407] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1558707753186973",
"created_by": "project:chromium",
"created_ts": "1558701045549310",
"failure_reason": "BUILD_FAILURE",
"id": "8912577208420964080",
"parameters_json": "{\"builder_name\": \"ToTiOS\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"61f5138f81ad66f582700f14dbc9d7fe6e5100ee\"}}",
"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\": \"build286-m9\", \"branch\": \"refs/heads/master\", \"buildername\": \"ToTiOS\", \"buildnumber\": 7875, \"got_angle_revision\": \"de15ed370d5368bc313eee282eb743b07a588c8b\", \"got_dawn_revision\": \"c391fb7c69fd50c55e566f9612d92b2f5d251524\", \"got_nacl_revision\": \"0ddc033406886a709b901e0c312872529f9705e8\", \"got_revision\": \"61f5138f81ad66f582700f14dbc9d7fe6e5100ee\", \"got_revision_cp\": \"refs/heads/master@{#663037}\", \"got_swarming_client_revision\": \"779c4f0f8488c64587b75dbb001d18c3c0c4cda9\", \"got_v8_revision\": \"785b71d8f6c054852897836640a71cb329725dba\", \"got_v8_revision_cp\": \"refs/heads/7.6.268@{#1}\", \"got_webrtc_revision\": \"ce723234bacfd0eac80a31d2a7540e9e33371175\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28051}\", \"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\": \"61f5138f81ad66f582700f14dbc9d7fe6e5100ee\", \"swarm_hashes\": {\"0\": \"e94d2b2cf66832810c6c00217501f11f5755ca46\", \"1\": \"03e50e641c0efb9a3afbf5c9172ede045931f338\", \"10\": \"8f411c3da55cb6823fc8edbc3c7947ccce5612ef\", \"11\": \"9a67c7074101200ee264ca13dfd25afbd95f3629\", \"12\": \"1e755963794f637b47f1ea1c0eaa4fa624ac4395\", \"13\": \"401b5ece0ff1e10a981c34f89f9ce4a0b646c43d\", \"14\": \"88e36ab47a2a6bfa139bf5b284a783f5ae97dbe6\", \"15\": \"6e0206646a6d61b37b68d0d443b06d511fa4cf3f\", \"16\": \"1b85d57971952d3ae63e03539206eecc83c841b0\", \"2\": \"80f02f03a70ac0cb1f41f04eea6c63e58d4ce476\", \"3\": \"4b973ce6563b5d08c489a898b9eac5bbf17eaa2e\", \"4\": \"de3feeb499a95005f329a796dbcd4c888e7090ae\", \"5\": \"324806deac0d6e16abe6dc8cfbd6302a70c2b183\", \"6\": \"ab35ef8c4a3cc42b808084bdc508bda78a4a43e0\", \"7\": \"a1bdfa6d608cd4f9a9b8d7fc3e7bb173f69ffb79\", \"8\": \"37eeff7179db5ac068e19535c216842bbf0132e6\", \"9\": \"9cd4d5a9cf602ef43f8bfbafe079c0d27fea02ba\"}}, \"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\": [\"build286-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\": [\"4303-bae210e\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-atl\", \"us-atl-golo\", \"us-atl-golo-m9\"]}}, \"ui\": {\"info\": \"Failed ios_web_inttests (iPhone 6s iOS 12.1).\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1558701190765288",
"status": "COMPLETED",
"status_changed_ts": "1558701191283829",
"tags": [
"build_address:luci.chromium.ci/ToTiOS/7875",
"builder:ToTiOS",
"buildset:commit/git/61f5138f81ad66f582700f14dbc9d7fe6e5100ee",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/61f5138f81ad66f582700f14dbc9d7fe6e5100ee",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9078007270446691552",
"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/8912577208420964080/+/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:4502a404d1d7ca10",
"user_agent:luci-scheduler"
],
"updated_ts": "1558707753550200",
"url": "https://ci.chromium.org/b/8912577208420964080",
"utcnow_ts": "1558707754390943"
}
}
[14:22:34.407] Invocation finished in 1h51m50.174042557s with status FAILED