[19:32:44.692] New invocation is queued and will start shortly
[19:32:45.816] Starting the invocation (attempt 1)
[19:32:45.855] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:32:45.855] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:32:45.855] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9066384308345818288",
"parameters_json": "{\"builder_name\":\"ToTiOS\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"65107121555167a3db39de5633c3297f7e861315\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:ToTiOS",
"scheduler_invocation_id:9066384308345818288",
"scheduler_job_id:chromium/ToTiOS",
"user_agent:luci-scheduler",
"buildset:commit/git/65107121555167a3db39de5633c3297f7e861315",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/65107121555167a3db39de5633c3297f7e861315",
"gitiles_ref:refs/heads/master"
]
}
[19:32:47.605] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1569785566535976",
"id": "8900954245742727264",
"parameters_json": "{\"builder_name\": \"ToTiOS\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"65107121555167a3db39de5633c3297f7e861315\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1569785567357936",
"tags": [
"build_address:luci.chromium.ci/ToTiOS/10152",
"builder:ToTiOS",
"buildset:commit/git/65107121555167a3db39de5633c3297f7e861315",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/65107121555167a3db39de5633c3297f7e861315",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9066384308345818288",
"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/8900954245742727264/+/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": "1569785567358055",
"url": "https://ci.chromium.org/b/8900954245742727264",
"utcnow_ts": "1569785567591852"
}
}
[19:32:47.605] Task URL: https://ci.chromium.org/b/8900954245742727264
[19:32:47.605] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:2:0) after 1m0s
[19:33:47.632] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:2:0)
[19:33:47.632] Timer tick, asking Buildbucket for the build status
[19:33:49.035] Build 8900954245742727264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:33:49.035] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:3:0) after 1m0s
[19:34:49.048] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:3:0)
[19:34:49.048] Timer tick, asking Buildbucket for the build status
[19:34:49.634] Build 8900954245742727264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:34:49.634] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:4:0) after 1m0s
[19:35:49.661] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:4:0)
[19:35:49.661] Timer tick, asking Buildbucket for the build status
[19:35:53.003] Build 8900954245742727264: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:35:53.003] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:5:0) after 1m0s
[19:35:58.131] Received PubSub notification, asking Buildbucket for the build status
[19:35:58.347] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:53.040] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:5:0)
[19:36:53.040] Timer tick, asking Buildbucket for the build status
[19:36:53.550] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:53.550] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:7:0) after 1m0s
[19:37:53.579] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:7:0)
[19:37:53.579] Timer tick, asking Buildbucket for the build status
[19:37:53.805] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:53.805] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:8:0) after 1m0s
[19:38:53.835] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:8:0)
[19:38:53.835] Timer tick, asking Buildbucket for the build status
[19:38:54.059] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:54.059] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:9:0) after 1m0s
[19:39:54.093] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:9:0)
[19:39:54.093] Timer tick, asking Buildbucket for the build status
[19:39:54.389] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:54.389] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:10:0) after 1m0s
[19:40:54.415] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:10:0)
[19:40:54.415] Timer tick, asking Buildbucket for the build status
[19:40:54.610] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:54.610] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:11:0) after 1m0s
[19:41:54.676] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:11:0)
[19:41:54.676] Timer tick, asking Buildbucket for the build status
[19:41:55.229] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:55.229] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:12:0) after 1m0s
[19:42:55.242] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:12:0)
[19:42:55.242] Timer tick, asking Buildbucket for the build status
[19:42:55.619] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:55.619] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:13:0) after 1m0s
[19:43:55.708] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:13:0)
[19:43:55.708] Timer tick, asking Buildbucket for the build status
[19:43:56.093] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:56.093] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:14:0) after 1m0s
[19:44:56.638] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:14:0)
[19:44:56.638] Timer tick, asking Buildbucket for the build status
[19:44:56.858] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:56.858] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:15:0) after 1m0s
[19:45:57.011] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:15:0)
[19:45:57.011] Timer tick, asking Buildbucket for the build status
[19:45:57.469] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:57.469] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:16:0) after 1m0s
[19:46:57.547] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:16:0)
[19:46:57.547] Timer tick, asking Buildbucket for the build status
[19:46:58.217] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:58.217] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:17:0) after 1m0s
[19:47:58.254] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:17:0)
[19:47:58.255] Timer tick, asking Buildbucket for the build status
[19:47:58.472] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:58.472] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:18:0) after 1m0s
[19:48:58.622] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:18:0)
[19:48:58.622] Timer tick, asking Buildbucket for the build status
[19:48:59.267] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:59.267] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:19:0) after 1m0s
[19:49:59.482] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:19:0)
[19:49:59.482] Timer tick, asking Buildbucket for the build status
[19:49:59.977] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:59.977] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:20:0) after 1m0s
[19:51:00.094] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:20:0)
[19:51:00.094] Timer tick, asking Buildbucket for the build status
[19:51:00.660] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:00.660] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:21:0) after 1m0s
[19:52:00.782] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:21:0)
[19:52:00.782] Timer tick, asking Buildbucket for the build status
[19:52:01.573] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:01.573] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:22:0) after 1m0s
[19:53:01.749] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:22:0)
[19:53:01.749] Timer tick, asking Buildbucket for the build status
[19:53:01.932] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:01.932] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:23:0) after 1m0s
[19:54:02.347] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:23:0)
[19:54:02.347] Timer tick, asking Buildbucket for the build status
[19:54:03.845] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:03.845] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:24:0) after 1m0s
[19:55:03.889] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:24:0)
[19:55:03.889] Timer tick, asking Buildbucket for the build status
[19:55:04.664] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:04.664] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:25:0) after 1m0s
[19:56:05.031] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:25:0)
[19:56:05.031] Timer tick, asking Buildbucket for the build status
[19:56:07.273] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:07.273] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:26:0) after 1m0s
[19:57:07.311] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:26:0)
[19:57:07.311] Timer tick, asking Buildbucket for the build status
[19:57:08.395] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:08.395] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:27:0) after 1m0s
[19:58:08.421] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:27:0)
[19:58:08.421] Timer tick, asking Buildbucket for the build status
[19:58:08.799] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:08.799] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:28:0) after 1m0s
[19:59:08.850] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:28:0)
[19:59:08.850] Timer tick, asking Buildbucket for the build status
[19:59:09.028] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:09.028] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:29:0) after 1m0s
[20:00:09.073] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:29:0)
[20:00:09.073] Timer tick, asking Buildbucket for the build status
[20:00:09.624] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:09.624] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:30:0) after 1m0s
[20:01:09.977] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:30:0)
[20:01:09.977] Timer tick, asking Buildbucket for the build status
[20:01:10.261] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:10.261] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:31:0) after 1m0s
[20:02:10.524] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:31:0)
[20:02:10.524] Timer tick, asking Buildbucket for the build status
[20:02:10.905] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:10.905] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:32:0) after 1m0s
[20:03:11.081] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:32:0)
[20:03:11.081] Timer tick, asking Buildbucket for the build status
[20:03:12.172] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:12.172] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:33:0) after 1m0s
[20:04:12.193] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:33:0)
[20:04:12.193] Timer tick, asking Buildbucket for the build status
[20:04:12.437] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:12.437] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:34:0) after 1m0s
[20:05:12.705] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:34:0)
[20:05:12.705] Timer tick, asking Buildbucket for the build status
[20:05:12.909] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:12.909] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:35:0) after 1m0s
[20:06:13.180] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:35:0)
[20:06:13.180] Timer tick, asking Buildbucket for the build status
[20:06:13.393] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:13.393] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:36:0) after 1m0s
[20:07:13.452] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:36:0)
[20:07:13.452] Timer tick, asking Buildbucket for the build status
[20:07:13.773] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:13.773] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:37:0) after 1m0s
[20:08:13.797] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:37:0)
[20:08:13.797] Timer tick, asking Buildbucket for the build status
[20:08:14.146] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:14.146] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:38:0) after 1m0s
[20:09:14.184] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:38:0)
[20:09:14.184] Timer tick, asking Buildbucket for the build status
[20:09:14.441] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:14.441] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:39:0) after 1m0s
[20:10:14.524] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:39:0)
[20:10:14.524] Timer tick, asking Buildbucket for the build status
[20:10:14.826] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:14.826] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:40:0) after 1m0s
[20:11:14.921] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:40:0)
[20:11:14.921] Timer tick, asking Buildbucket for the build status
[20:11:15.448] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:15.448] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:41:0) after 1m0s
[20:12:15.441] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:41:0)
[20:12:15.441] Timer tick, asking Buildbucket for the build status
[20:12:15.845] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:15.845] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:42:0) after 1m0s
[20:13:15.883] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:42:0)
[20:13:15.883] Timer tick, asking Buildbucket for the build status
[20:13:16.422] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:16.422] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:43:0) after 1m0s
[20:14:16.632] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:43:0)
[20:14:16.632] Timer tick, asking Buildbucket for the build status
[20:14:16.879] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:16.879] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:44:0) after 1m0s
[20:15:16.905] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:44:0)
[20:15:16.905] Timer tick, asking Buildbucket for the build status
[20:15:17.316] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:17.316] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:45:0) after 1m0s
[20:16:17.436] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:45:0)
[20:16:17.436] Timer tick, asking Buildbucket for the build status
[20:16:18.249] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:18.249] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:46:0) after 1m0s
[20:17:18.287] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:46:0)
[20:17:18.287] Timer tick, asking Buildbucket for the build status
[20:17:19.830] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:19.830] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:47:0) after 1m0s
[20:18:19.854] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:47:0)
[20:18:19.854] Timer tick, asking Buildbucket for the build status
[20:18:20.203] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:20.203] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:48:0) after 1m0s
[20:19:20.234] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:48:0)
[20:19:20.234] Timer tick, asking Buildbucket for the build status
[20:19:20.504] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:20.504] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:49:0) after 1m0s
[20:20:20.526] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:49:0)
[20:20:20.526] Timer tick, asking Buildbucket for the build status
[20:20:21.330] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:21.330] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:50:0) after 1m0s
[20:21:21.406] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:50:0)
[20:21:21.406] Timer tick, asking Buildbucket for the build status
[20:21:22.236] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:22.236] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:51:0) after 1m0s
[20:22:22.464] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:51:0)
[20:22:22.464] Timer tick, asking Buildbucket for the build status
[20:22:22.654] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:22.654] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:52:0) after 1m0s
[20:23:22.702] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:52:0)
[20:23:22.702] Timer tick, asking Buildbucket for the build status
[20:23:23.017] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:23.017] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:53:0) after 1m0s
[20:24:23.039] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:53:0)
[20:24:23.039] Timer tick, asking Buildbucket for the build status
[20:24:23.746] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:23.746] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:54:0) after 1m0s
[20:25:23.848] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:54:0)
[20:25:23.848] Timer tick, asking Buildbucket for the build status
[20:25:24.210] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:24.210] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:55:0) after 1m0s
[20:26:24.245] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:55:0)
[20:26:24.245] Timer tick, asking Buildbucket for the build status
[20:26:24.485] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:24.485] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:56:0) after 1m0s
[20:27:24.788] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:56:0)
[20:27:24.788] Timer tick, asking Buildbucket for the build status
[20:27:25.531] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:25.531] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:57:0) after 1m0s
[20:28:25.636] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:57:0)
[20:28:25.636] Timer tick, asking Buildbucket for the build status
[20:28:25.833] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:25.833] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:58:0) after 1m0s
[20:29:25.877] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:58:0)
[20:29:25.877] Timer tick, asking Buildbucket for the build status
[20:29:26.164] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:26.164] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:59:0) after 1m0s
[20:30:26.203] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:59:0)
[20:30:26.203] Timer tick, asking Buildbucket for the build status
[20:30:27.311] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:27.311] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:60:0) after 1m0s
[20:31:27.431] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:60:0)
[20:31:27.431] Timer tick, asking Buildbucket for the build status
[20:31:28.097] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:28.097] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:61:0) after 1m0s
[20:32:28.257] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:61:0)
[20:32:28.257] Timer tick, asking Buildbucket for the build status
[20:32:28.546] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:28.546] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:62:0) after 1m0s
[20:33:37.949] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:62:0)
[20:33:37.949] Timer tick, asking Buildbucket for the build status
[20:33:39.297] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:39.297] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:63:0) after 1m0s
[20:34:39.395] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:63:0)
[20:34:39.395] Timer tick, asking Buildbucket for the build status
[20:34:39.974] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:39.974] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:64:0) after 1m0s
[20:35:40.016] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:64:0)
[20:35:40.016] Timer tick, asking Buildbucket for the build status
[20:35:40.199] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:40.199] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:65:0) after 1m0s
[20:36:40.238] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:65:0)
[20:36:40.238] Timer tick, asking Buildbucket for the build status
[20:36:41.394] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:41.394] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:66:0) after 1m0s
[20:37:41.410] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:66:0)
[20:37:41.410] Timer tick, asking Buildbucket for the build status
[20:37:42.776] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:42.776] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:67:0) after 1m0s
[20:38:42.794] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:67:0)
[20:38:42.794] Timer tick, asking Buildbucket for the build status
[20:38:43.294] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:43.294] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:68:0) after 1m0s
[20:39:43.323] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:68:0)
[20:39:43.323] Timer tick, asking Buildbucket for the build status
[20:39:44.472] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:44.472] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:69:0) after 1m0s
[20:40:44.652] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:69:0)
[20:40:44.652] Timer tick, asking Buildbucket for the build status
[20:40:45.621] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:45.621] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:70:0) after 1m0s
[20:41:45.678] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:70:0)
[20:41:45.678] Timer tick, asking Buildbucket for the build status
[20:41:45.889] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:45.889] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:71:0) after 1m0s
[20:42:45.920] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:71:0)
[20:42:45.920] Timer tick, asking Buildbucket for the build status
[20:42:46.406] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:46.406] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:72:0) after 1m0s
[20:43:46.445] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:72:0)
[20:43:46.445] Timer tick, asking Buildbucket for the build status
[20:43:46.843] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:46.843] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:73:0) after 1m0s
[20:44:46.876] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:73:0)
[20:44:46.876] Timer tick, asking Buildbucket for the build status
[20:44:48.718] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:48.718] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:74:0) after 1m0s
[20:45:48.741] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:74:0)
[20:45:48.741] Timer tick, asking Buildbucket for the build status
[20:45:49.133] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:49.133] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:75:0) after 1m0s
[20:46:49.157] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:75:0)
[20:46:49.157] Timer tick, asking Buildbucket for the build status
[20:46:49.440] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:49.440] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:76:0) after 1m0s
[20:47:49.462] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:76:0)
[20:47:49.462] Timer tick, asking Buildbucket for the build status
[20:47:49.703] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:49.703] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:77:0) after 1m0s
[20:48:49.763] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:77:0)
[20:48:49.763] Timer tick, asking Buildbucket for the build status
[20:48:49.989] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:49.989] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:78:0) after 1m0s
[20:49:50.022] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:78:0)
[20:49:50.022] Timer tick, asking Buildbucket for the build status
[20:49:51.463] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:51.463] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:79:0) after 1m0s
[20:50:51.457] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:79:0)
[20:50:51.457] Timer tick, asking Buildbucket for the build status
[20:50:51.795] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:51.796] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:80:0) after 1m0s
[20:51:51.826] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:80:0)
[20:51:51.826] Timer tick, asking Buildbucket for the build status
[20:51:53.001] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:53.001] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:81:0) after 1m0s
[20:52:53.146] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:81:0)
[20:52:53.146] Timer tick, asking Buildbucket for the build status
[20:52:53.333] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:53.333] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:82:0) after 1m0s
[20:53:53.410] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:82:0)
[20:53:53.410] Timer tick, asking Buildbucket for the build status
[20:53:53.842] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:53.842] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:83:0) after 1m0s
[20:54:54.005] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:83:0)
[20:54:54.005] Timer tick, asking Buildbucket for the build status
[20:54:55.131] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:55.131] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:84:0) after 1m0s
[20:55:55.158] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:84:0)
[20:55:55.158] Timer tick, asking Buildbucket for the build status
[20:55:55.773] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:55.773] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:85:0) after 1m0s
[20:56:55.802] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:85:0)
[20:56:55.802] Timer tick, asking Buildbucket for the build status
[20:56:56.110] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:56.110] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:86:0) after 1m0s
[20:57:56.138] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:86:0)
[20:57:56.138] Timer tick, asking Buildbucket for the build status
[20:57:58.830] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:58.830] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:87:0) after 1m0s
[20:58:58.955] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:87:0)
[20:58:58.955] Timer tick, asking Buildbucket for the build status
[20:58:59.685] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:59.685] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:88:0) after 1m0s
[20:59:59.784] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:88:0)
[20:59:59.784] Timer tick, asking Buildbucket for the build status
[21:00:00.082] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:00.082] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:89:0) after 1m0s
[21:01:00.423] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:89:0)
[21:01:00.423] Timer tick, asking Buildbucket for the build status
[21:01:00.659] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:00.659] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:90:0) after 1m0s
[21:02:00.702] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:90:0)
[21:02:00.702] Timer tick, asking Buildbucket for the build status
[21:02:00.958] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:00.958] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:91:0) after 1m0s
[21:03:01.001] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:91:0)
[21:03:01.001] Timer tick, asking Buildbucket for the build status
[21:03:03.665] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:03.665] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:92:0) after 1m0s
[21:04:03.707] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:92:0)
[21:04:03.707] Timer tick, asking Buildbucket for the build status
[21:04:03.986] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:03.986] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:93:0) after 1m0s
[21:05:04.020] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:93:0)
[21:05:04.020] Timer tick, asking Buildbucket for the build status
[21:05:04.243] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:04.243] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:94:0) after 1m0s
[21:06:04.327] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:94:0)
[21:06:04.327] Timer tick, asking Buildbucket for the build status
[21:06:04.959] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:04.959] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:95:0) after 1m0s
[21:07:05.007] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:95:0)
[21:07:05.007] Timer tick, asking Buildbucket for the build status
[21:07:05.215] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:05.216] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:96:0) after 1m0s
[21:08:05.353] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:96:0)
[21:08:05.353] Timer tick, asking Buildbucket for the build status
[21:08:08.335] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:08.335] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:97:0) after 1m0s
[21:09:08.391] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:97:0)
[21:09:08.391] Timer tick, asking Buildbucket for the build status
[21:09:08.975] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:08.975] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:98:0) after 1m0s
[21:10:09.151] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:98:0)
[21:10:09.151] Timer tick, asking Buildbucket for the build status
[21:10:09.515] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:09.515] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:99:0) after 1m0s
[21:11:09.576] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:99:0)
[21:11:09.576] Timer tick, asking Buildbucket for the build status
[21:11:10.978] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:10.978] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:100:0) after 1m0s
[21:12:11.046] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:100:0)
[21:12:11.046] Timer tick, asking Buildbucket for the build status
[21:12:11.334] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:11.334] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:101:0) after 1m0s
[21:13:11.361] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:101:0)
[21:13:11.361] Timer tick, asking Buildbucket for the build status
[21:13:11.539] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:11.539] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:102:0) after 1m0s
[21:14:11.567] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:102:0)
[21:14:11.567] Timer tick, asking Buildbucket for the build status
[21:14:11.834] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:11.834] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:103:0) after 1m0s
[21:15:11.884] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:103:0)
[21:15:11.884] Timer tick, asking Buildbucket for the build status
[21:15:12.053] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:12.053] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:104:0) after 1m0s
[21:16:12.267] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:104:0)
[21:16:12.267] Timer tick, asking Buildbucket for the build status
[21:16:15.201] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:15.201] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:105:0) after 1m0s
[21:17:15.240] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:105:0)
[21:17:15.240] Timer tick, asking Buildbucket for the build status
[21:17:17.371] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:17.371] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:106:0) after 1m0s
[21:18:17.533] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:106:0)
[21:18:17.533] Timer tick, asking Buildbucket for the build status
[21:18:18.006] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:18.006] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:107:0) after 1m0s
[21:19:18.158] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:107:0)
[21:19:18.158] Timer tick, asking Buildbucket for the build status
[21:19:18.427] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:18.427] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:108:0) after 1m0s
[21:20:18.446] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:108:0)
[21:20:18.446] Timer tick, asking Buildbucket for the build status
[21:20:18.667] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:18.667] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:109:0) after 1m0s
[21:21:18.830] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:109:0)
[21:21:18.830] Timer tick, asking Buildbucket for the build status
[21:21:19.245] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:19.245] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:110:0) after 1m0s
[21:22:19.371] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:110:0)
[21:22:19.371] Timer tick, asking Buildbucket for the build status
[21:22:19.897] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:19.897] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:111:0) after 1m0s
[21:23:20.143] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:111:0)
[21:23:20.143] Timer tick, asking Buildbucket for the build status
[21:23:21.259] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:21.259] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:112:0) after 1m0s
[21:24:21.286] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:112:0)
[21:24:21.286] Timer tick, asking Buildbucket for the build status
[21:24:21.529] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:21.529] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:113:0) after 1m0s
[21:25:21.594] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:113:0)
[21:25:21.594] Timer tick, asking Buildbucket for the build status
[21:25:21.815] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:21.815] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:114:0) after 1m0s
[21:26:21.862] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:114:0)
[21:26:21.862] Timer tick, asking Buildbucket for the build status
[21:26:22.667] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:22.667] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:115:0) after 1m0s
[21:27:22.760] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:115:0)
[21:27:22.760] Timer tick, asking Buildbucket for the build status
[21:27:23.118] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:23.118] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:116:0) after 1m0s
[21:28:23.151] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:116:0)
[21:28:23.151] Timer tick, asking Buildbucket for the build status
[21:28:24.359] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:24.359] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:117:0) after 1m0s
[21:29:24.541] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:117:0)
[21:29:24.541] Timer tick, asking Buildbucket for the build status
[21:29:27.124] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:27.124] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:118:0) after 1m0s
[21:30:27.344] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:118:0)
[21:30:27.344] Timer tick, asking Buildbucket for the build status
[21:30:27.630] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:27.630] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:119:0) after 1m0s
[21:31:27.673] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:119:0)
[21:31:27.673] Timer tick, asking Buildbucket for the build status
[21:31:27.867] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:27.867] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:120:0) after 1m0s
[21:32:27.911] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:120:0)
[21:32:27.912] Timer tick, asking Buildbucket for the build status
[21:32:28.173] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:28.173] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:121:0) after 1m0s
[21:33:28.198] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:121:0)
[21:33:28.198] Timer tick, asking Buildbucket for the build status
[21:33:28.544] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:28.544] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:122:0) after 1m0s
[21:34:28.573] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:122:0)
[21:34:28.573] Timer tick, asking Buildbucket for the build status
[21:34:28.826] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:28.826] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:123:0) after 1m0s
[21:35:28.933] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:123:0)
[21:35:28.933] Timer tick, asking Buildbucket for the build status
[21:35:29.474] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:29.474] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:124:0) after 1m0s
[21:36:29.502] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:124:0)
[21:36:29.502] Timer tick, asking Buildbucket for the build status
[21:36:29.693] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:29.693] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:125:0) after 1m0s
[21:37:29.864] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:125:0)
[21:37:29.864] Timer tick, asking Buildbucket for the build status
[21:37:30.467] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:30.467] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:126:0) after 1m0s
[21:38:30.553] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:126:0)
[21:38:30.553] Timer tick, asking Buildbucket for the build status
[21:38:32.243] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:32.243] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:127:0) after 1m0s
[21:39:32.403] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:127:0)
[21:39:32.403] Timer tick, asking Buildbucket for the build status
[21:39:33.564] Build 8900954245742727264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:33.564] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9066384308345818288:128:0) after 1m0s
[21:39:40.966] Received PubSub notification, asking Buildbucket for the build status
[21:39:41.546] Build 8900954245742727264: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[21:39:41.546] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1569793180425229",
"created_by": "project:chromium",
"created_ts": "1569785566535976",
"failure_reason": "BUILD_FAILURE",
"id": "8900954245742727264",
"parameters_json": "{\"builder_name\": \"ToTiOS\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"65107121555167a3db39de5633c3297f7e861315\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Failed ios_web_inttests (iPhone 6s iOS 12.2).\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"build285-m9\", \"branch\": \"refs/heads/master\", \"buildername\": \"ToTiOS\", \"buildnumber\": 10152, \"got_angle_revision\": \"71c1138d6739b58cb8cc849ea1a2fd8c9f2776c3\", \"got_dawn_revision\": \"52bd6b7da6a758888e99436656e836f178b762af\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"65107121555167a3db39de5633c3297f7e861315\", \"got_revision_cp\": \"refs/heads/master@{#701002}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_v8_revision\": \"656e25f970901daff67319c68a19b109bee5ad1a\", \"got_v8_revision_cp\": \"refs/heads/7.9.162@{#1}\", \"got_webrtc_revision\": \"578270d9d23a12e8400bacfc7e28f3536ab74a68\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#29334}\", \"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\": \"65107121555167a3db39de5633c3297f7e861315\", \"swarm_hashes\": {\"0\": \"77ba94a848461bb9b5613a95d9e10816ac8c3d7b\", \"1\": \"ff534d57baae4644ad06e6308392093d312fc809\", \"10\": \"001ec45cc1eb1ce756b563da11be2e69180662f1\", \"11\": \"39771a5f5f64d3705ab3728b9e097c595bad3b72\", \"12\": \"3d1d3cc4e52847174410adf28b23afebc461f2c3\", \"13\": \"f8cef576b84145c1cdedb421e67280ac6a3bd5ef\", \"14\": \"bfde4194301ff07c9f8ff7dcd7992e96594f3886\", \"15\": \"7db46dc0fcd67e0cc6444e8a1c32cdc020994197\", \"16\": \"2a69ee40405a9340e3e88da7c63b4c26d695ca9f\", \"2\": \"68b4b5701f0e98b29fd45a20d8c66b284ab1101c\", \"3\": \"42790c9c45974b41b484d49a2fc2f597205dd82a\", \"4\": \"dd836978a28fe42fa6f06f74b341d016162c8620\", \"5\": \"6919bd77ff59c1ced3521b78677c9ed910f6044e\", \"6\": \"4a74e21f38d53dff97a95bcc67edd20384c072de\", \"7\": \"929f6a25bc27d71418458cadf8778f17115d820f\", \"8\": \"c46cb363ad3710434095dbf8f2aeabd7e2b53287\", \"9\": \"e1b19a0afd0652dba61f46125bc0c23bc79b51b1\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4a4feefdd7e355061708c01720b06a0ca8c49ffc9968a2ebc674541738676b8f_v2\", \"builder_5372538a354f163a20d332d881c6c5d85e35654ac8a57c760866f12033f8a8a5_v2\", \"builder_69caa34b8f39daac28a821cac2619730401d46a6479812cc34796e3bf180418a_v2\", \"builder_ae5debd901c74ee0906916cb62621bceb2a8b657b42c2e2e9e89a0f6a2e5e53b_v2\", \"builder_d42b89eab403033f5a2a8102ec9c436397350cedb439d5e09b11874a5784adb4_v2\", \"git\", \"goma_v2\", \"vpython\", \"xcode_ios_11a419c\", \"xcode_ios_11a420a\", \"xcode_ios_11m392r\"], \"cores\": [\"24\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-E5-2697_v2\"], \"gce\": [\"0\"], \"gpu\": [\"1002\", \"1002:679e\", \"1002:679e-4.0.11-3.2.8\"], \"hidpi\": [\"0\"], \"id\": [\"build285-m9\"], \"mac_model\": [\"MacPro6,1\"], \"machine_type\": [\"n1-standard-16\"], \"os\": [\"Mac\", \"Mac-10.14\", \"Mac-10.14.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.10\"], \"server_version\": [\"4443-3a52fcb\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-atl\", \"us-atl-golo\", \"us-atl-golo-m9\"]}}, \"ui\": {\"info\": \"Failed ios_web_inttests (iPhone 6s iOS 12.2).\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1569785757226735",
"status": "COMPLETED",
"status_changed_ts": "1569785757527275",
"tags": [
"build_address:luci.chromium.ci/ToTiOS/10152",
"builder:ToTiOS",
"buildset:commit/git/65107121555167a3db39de5633c3297f7e861315",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/65107121555167a3db39de5633c3297f7e861315",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9066384308345818288",
"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/8900954245742727264/+/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:479754619a1e4510",
"user_agent:luci-scheduler"
],
"updated_ts": "1569793180509525",
"url": "https://ci.chromium.org/b/8900954245742727264",
"utcnow_ts": "1569793181532854"
}
}
[21:39:41.547] Invocation finished in 2h6m56.877944078s with status FAILED