[14:11:44.742] New invocation is queued and will start shortly
[14:11:45.909] Starting the invocation (attempt 1)
[14:11:45.951] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:11:45.951] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:11:45.951] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9059428537448803232",
"parameters_json": "{\"builder_name\":\"ToTiOS\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"1ed08d114236341db1bab4feeb48299d03b5d720\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:ToTiOS",
"scheduler_invocation_id:9059428537448803232",
"scheduler_job_id:chromium/ToTiOS",
"user_agent:luci-scheduler",
"buildset:commit/git/1ed08d114236341db1bab4feeb48299d03b5d720",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1ed08d114236341db1bab4feeb48299d03b5d720",
"gitiles_ref:refs/heads/master"
]
}
[14:11:46.856] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1576419106140103",
"id": "8893998475318386016",
"parameters_json": "{\"builder_name\": \"ToTiOS\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1ed08d114236341db1bab4feeb48299d03b5d720\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1576419106541259",
"tags": [
"build_address:luci.chromium.ci/ToTiOS/11556",
"builder:ToTiOS",
"buildset:commit/git/1ed08d114236341db1bab4feeb48299d03b5d720",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1ed08d114236341db1bab4feeb48299d03b5d720",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059428537448803232",
"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/8893998475318386016/+/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": "1576419106541432",
"url": "https://ci.chromium.org/b/8893998475318386016",
"utcnow_ts": "1576419106847800"
}
}
[14:11:46.856] Task URL: https://ci.chromium.org/b/8893998475318386016
[14:11:46.856] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:2:0) after 1m0s
[14:12:09.459] Received PubSub notification, asking Buildbucket for the build status
[14:12:10.226] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:46.904] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:2:0)
[14:12:46.904] Timer tick, asking Buildbucket for the build status
[14:12:47.370] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:47.370] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:4:0) after 1m0s
[14:13:47.541] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:4:0)
[14:13:47.541] Timer tick, asking Buildbucket for the build status
[14:13:47.713] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:47.713] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:5:0) after 1m0s
[14:14:47.833] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:5:0)
[14:14:47.833] Timer tick, asking Buildbucket for the build status
[14:14:48.621] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:48.621] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:6:0) after 1m0s
[14:15:48.635] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:6:0)
[14:15:48.635] Timer tick, asking Buildbucket for the build status
[14:15:48.837] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:48.837] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:7:0) after 1m0s
[14:16:48.875] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:7:0)
[14:16:48.875] Timer tick, asking Buildbucket for the build status
[14:16:49.045] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:49.045] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:8:0) after 1m0s
[14:17:49.118] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:8:0)
[14:17:49.118] Timer tick, asking Buildbucket for the build status
[14:17:49.296] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:49.296] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:9:0) after 1m0s
[14:18:49.429] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:9:0)
[14:18:49.429] Timer tick, asking Buildbucket for the build status
[14:18:49.589] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:49.589] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:10:0) after 1m0s
[14:19:49.616] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:10:0)
[14:19:49.616] Timer tick, asking Buildbucket for the build status
[14:19:49.790] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:49.790] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:11:0) after 1m0s
[14:20:49.991] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:11:0)
[14:20:49.991] Timer tick, asking Buildbucket for the build status
[14:20:50.209] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:50.210] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:12:0) after 1m0s
[14:21:50.230] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:12:0)
[14:21:50.230] Timer tick, asking Buildbucket for the build status
[14:21:50.397] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:50.397] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:13:0) after 1m0s
[14:22:50.423] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:13:0)
[14:22:50.423] Timer tick, asking Buildbucket for the build status
[14:22:50.537] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:50.537] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:14:0) after 1m0s
[14:23:50.563] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:14:0)
[14:23:50.563] Timer tick, asking Buildbucket for the build status
[14:23:51.287] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:51.287] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:15:0) after 1m0s
[14:24:51.310] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:15:0)
[14:24:51.310] Timer tick, asking Buildbucket for the build status
[14:24:51.957] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:51.957] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:16:0) after 1m0s
[14:25:51.978] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:16:0)
[14:25:51.978] Timer tick, asking Buildbucket for the build status
[14:25:53.152] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:53.152] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:17:0) after 1m0s
[14:26:53.216] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:17:0)
[14:26:53.216] Timer tick, asking Buildbucket for the build status
[14:26:53.372] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:53.372] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:18:0) after 1m0s
[14:27:53.391] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:18:0)
[14:27:53.391] Timer tick, asking Buildbucket for the build status
[14:27:54.075] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:54.075] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:19:0) after 1m0s
[14:28:54.118] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:19:0)
[14:28:54.118] Timer tick, asking Buildbucket for the build status
[14:28:54.309] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:54.309] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:20:0) after 1m0s
[14:29:54.373] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:20:0)
[14:29:54.373] Timer tick, asking Buildbucket for the build status
[14:29:54.608] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:54.608] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:21:0) after 1m0s
[14:30:54.632] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:21:0)
[14:30:54.632] Timer tick, asking Buildbucket for the build status
[14:30:54.797] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:54.797] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:22:0) after 1m0s
[14:31:54.821] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:22:0)
[14:31:54.821] Timer tick, asking Buildbucket for the build status
[14:31:54.973] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:54.973] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:23:0) after 1m0s
[14:32:54.998] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:23:0)
[14:32:54.998] Timer tick, asking Buildbucket for the build status
[14:32:55.459] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:55.459] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:24:0) after 1m0s
[14:33:55.481] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:24:0)
[14:33:55.481] Timer tick, asking Buildbucket for the build status
[14:33:55.709] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:55.709] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:25:0) after 1m0s
[14:34:55.747] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:25:0)
[14:34:55.747] Timer tick, asking Buildbucket for the build status
[14:34:55.992] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:55.992] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:26:0) after 1m0s
[14:35:56.016] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:26:0)
[14:35:56.016] Timer tick, asking Buildbucket for the build status
[14:35:56.821] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:56.821] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:27:0) after 1m0s
[14:36:56.918] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:27:0)
[14:36:56.918] Timer tick, asking Buildbucket for the build status
[14:36:57.443] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:57.443] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:28:0) after 1m0s
[14:37:57.489] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:28:0)
[14:37:57.489] Timer tick, asking Buildbucket for the build status
[14:37:57.646] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:57.646] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:29:0) after 1m0s
[14:38:57.668] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:29:0)
[14:38:57.668] Timer tick, asking Buildbucket for the build status
[14:38:58.031] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:58.031] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:30:0) after 1m0s
[14:39:58.072] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:30:0)
[14:39:58.072] Timer tick, asking Buildbucket for the build status
[14:39:58.257] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:58.257] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:31:0) after 1m0s
[14:40:58.248] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:31:0)
[14:40:58.248] Timer tick, asking Buildbucket for the build status
[14:40:58.573] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:58.573] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:32:0) after 1m0s
[14:41:58.654] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:32:0)
[14:41:58.654] Timer tick, asking Buildbucket for the build status
[14:41:59.068] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:59.068] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:33:0) after 1m0s
[14:42:59.091] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:33:0)
[14:42:59.091] Timer tick, asking Buildbucket for the build status
[14:42:59.279] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:59.279] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:34:0) after 1m0s
[14:43:59.345] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:34:0)
[14:43:59.345] Timer tick, asking Buildbucket for the build status
[14:43:59.648] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:59.649] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:35:0) after 1m0s
[14:44:59.750] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:35:0)
[14:44:59.750] Timer tick, asking Buildbucket for the build status
[14:44:59.921] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:59.921] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:36:0) after 1m0s
[14:45:59.954] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:36:0)
[14:45:59.954] Timer tick, asking Buildbucket for the build status
[14:46:00.265] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:00.265] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:37:0) after 1m0s
[14:47:00.289] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:37:0)
[14:47:00.289] Timer tick, asking Buildbucket for the build status
[14:47:00.443] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:00.443] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:38:0) after 1m0s
[14:48:00.968] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:38:0)
[14:48:00.968] Timer tick, asking Buildbucket for the build status
[14:48:01.128] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:01.128] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:39:0) after 1m0s
[14:49:01.159] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:39:0)
[14:49:01.159] Timer tick, asking Buildbucket for the build status
[14:49:01.368] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:01.368] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:40:0) after 1m0s
[14:50:01.391] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:40:0)
[14:50:01.391] Timer tick, asking Buildbucket for the build status
[14:50:01.588] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:01.589] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:41:0) after 1m0s
[14:51:01.653] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:41:0)
[14:51:01.653] Timer tick, asking Buildbucket for the build status
[14:51:01.902] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:01.902] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:42:0) after 1m0s
[14:52:01.925] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:42:0)
[14:52:01.925] Timer tick, asking Buildbucket for the build status
[14:52:02.104] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:02.104] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:43:0) after 1m0s
[14:53:02.179] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:43:0)
[14:53:02.179] Timer tick, asking Buildbucket for the build status
[14:53:02.386] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:02.386] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:44:0) after 1m0s
[14:54:02.391] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:44:0)
[14:54:02.391] Timer tick, asking Buildbucket for the build status
[14:54:02.636] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:02.636] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:45:0) after 1m0s
[14:55:02.878] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:45:0)
[14:55:02.878] Timer tick, asking Buildbucket for the build status
[14:55:03.056] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:03.056] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:46:0) after 1m0s
[14:56:03.203] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:46:0)
[14:56:03.203] Timer tick, asking Buildbucket for the build status
[14:56:03.431] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:03.431] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:47:0) after 1m0s
[14:57:03.557] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:47:0)
[14:57:03.557] Timer tick, asking Buildbucket for the build status
[14:57:03.807] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:03.807] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:48:0) after 1m0s
[14:58:04.144] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:48:0)
[14:58:04.144] Timer tick, asking Buildbucket for the build status
[14:58:04.311] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:04.311] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:49:0) after 1m0s
[14:59:04.302] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:49:0)
[14:59:04.302] Timer tick, asking Buildbucket for the build status
[14:59:04.486] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:04.486] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:50:0) after 1m0s
[15:00:04.595] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:50:0)
[15:00:04.595] Timer tick, asking Buildbucket for the build status
[15:00:04.797] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:04.797] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:51:0) after 1m0s
[15:01:05.265] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:51:0)
[15:01:05.265] Timer tick, asking Buildbucket for the build status
[15:01:05.818] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:05.818] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:52:0) after 1m0s
[15:02:05.973] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:52:0)
[15:02:05.973] Timer tick, asking Buildbucket for the build status
[15:02:06.214] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:06.214] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:53:0) after 1m0s
[15:03:06.418] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:53:0)
[15:03:06.418] Timer tick, asking Buildbucket for the build status
[15:03:07.467] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:07.467] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:54:0) after 1m0s
[15:04:07.718] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:54:0)
[15:04:07.718] Timer tick, asking Buildbucket for the build status
[15:04:08.022] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:08.022] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:55:0) after 1m0s
[15:05:08.120] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:55:0)
[15:05:08.120] Timer tick, asking Buildbucket for the build status
[15:05:10.365] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:10.365] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:56:0) after 1m0s
[15:06:10.462] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:56:0)
[15:06:10.462] Timer tick, asking Buildbucket for the build status
[15:06:10.631] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:10.631] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:57:0) after 1m0s
[15:07:10.851] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:57:0)
[15:07:10.851] Timer tick, asking Buildbucket for the build status
[15:07:11.045] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:11.045] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:58:0) after 1m0s
[15:08:11.172] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:58:0)
[15:08:11.172] Timer tick, asking Buildbucket for the build status
[15:08:11.366] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:11.366] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:59:0) after 1m0s
[15:09:11.517] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:59:0)
[15:09:11.517] Timer tick, asking Buildbucket for the build status
[15:09:11.959] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:11.959] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:60:0) after 1m0s
[15:10:12.054] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:60:0)
[15:10:12.054] Timer tick, asking Buildbucket for the build status
[15:10:12.291] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:12.291] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:61:0) after 1m0s
[15:11:12.385] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:61:0)
[15:11:12.385] Timer tick, asking Buildbucket for the build status
[15:11:12.567] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:12.567] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:62:0) after 1m0s
[15:12:12.629] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:62:0)
[15:12:12.629] Timer tick, asking Buildbucket for the build status
[15:12:13.105] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:13.105] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:63:0) after 1m0s
[15:13:13.148] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:63:0)
[15:13:13.148] Timer tick, asking Buildbucket for the build status
[15:13:13.366] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:13.366] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:64:0) after 1m0s
[15:14:13.425] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:64:0)
[15:14:13.425] Timer tick, asking Buildbucket for the build status
[15:14:13.975] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:13.975] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:65:0) after 1m0s
[15:15:14.077] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:65:0)
[15:15:14.077] Timer tick, asking Buildbucket for the build status
[15:15:14.260] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:14.261] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:66:0) after 1m0s
[15:16:14.318] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:66:0)
[15:16:14.318] Timer tick, asking Buildbucket for the build status
[15:16:14.499] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:14.499] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:67:0) after 1m0s
[15:17:14.612] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:67:0)
[15:17:14.612] Timer tick, asking Buildbucket for the build status
[15:17:15.096] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:15.096] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:68:0) after 1m0s
[15:18:15.183] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:68:0)
[15:18:15.183] Timer tick, asking Buildbucket for the build status
[15:18:15.308] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:15.308] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:69:0) after 1m0s
[15:19:15.433] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:69:0)
[15:19:15.433] Timer tick, asking Buildbucket for the build status
[15:19:15.866] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:15.866] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:70:0) after 1m0s
[15:20:16.034] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:70:0)
[15:20:16.035] Timer tick, asking Buildbucket for the build status
[15:20:16.849] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:16.849] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:71:0) after 1m0s
[15:21:16.981] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:71:0)
[15:21:16.981] Timer tick, asking Buildbucket for the build status
[15:21:19.769] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:19.769] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:72:0) after 1m0s
[15:22:19.829] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:72:0)
[15:22:19.829] Timer tick, asking Buildbucket for the build status
[15:22:20.038] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:20.038] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:73:0) after 1m0s
[15:23:20.062] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:73:0)
[15:23:20.062] Timer tick, asking Buildbucket for the build status
[15:23:20.220] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:20.220] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:74:0) after 1m0s
[15:24:20.257] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:74:0)
[15:24:20.257] Timer tick, asking Buildbucket for the build status
[15:24:21.152] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:21.152] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:75:0) after 1m0s
[15:25:21.206] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:75:0)
[15:25:21.206] Timer tick, asking Buildbucket for the build status
[15:25:21.685] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:21.685] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:76:0) after 1m0s
[15:26:21.708] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:76:0)
[15:26:21.708] Timer tick, asking Buildbucket for the build status
[15:26:21.975] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:21.975] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:77:0) after 1m0s
[15:27:22.015] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:77:0)
[15:27:22.015] Timer tick, asking Buildbucket for the build status
[15:27:22.609] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:22.609] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:78:0) after 1m0s
[15:28:22.644] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:78:0)
[15:28:22.644] Timer tick, asking Buildbucket for the build status
[15:28:23.541] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:23.541] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:79:0) after 1m0s
[15:29:23.638] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:79:0)
[15:29:23.638] Timer tick, asking Buildbucket for the build status
[15:29:24.752] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:24.752] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:80:0) after 1m0s
[15:30:24.786] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:80:0)
[15:30:24.786] Timer tick, asking Buildbucket for the build status
[15:30:24.987] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:24.987] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:81:0) after 1m0s
[15:31:25.025] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:81:0)
[15:31:25.025] Timer tick, asking Buildbucket for the build status
[15:31:25.374] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:25.374] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:82:0) after 1m0s
[15:32:25.444] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:82:0)
[15:32:25.444] Timer tick, asking Buildbucket for the build status
[15:32:25.645] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:25.646] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:83:0) after 1m0s
[15:33:25.682] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:83:0)
[15:33:25.682] Timer tick, asking Buildbucket for the build status
[15:33:26.611] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:26.611] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:84:0) after 1m0s
[15:34:30.958] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:84:0)
[15:34:30.958] Timer tick, asking Buildbucket for the build status
[15:34:31.147] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:31.148] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:85:0) after 1m0s
[15:35:31.141] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:85:0)
[15:35:31.141] Timer tick, asking Buildbucket for the build status
[15:35:33.161] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:33.161] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:86:0) after 1m0s
[15:36:33.184] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:86:0)
[15:36:33.184] Timer tick, asking Buildbucket for the build status
[15:36:33.678] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:33.678] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:87:0) after 1m0s
[15:37:33.796] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:87:0)
[15:37:33.796] Timer tick, asking Buildbucket for the build status
[15:37:34.097] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:34.097] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:88:0) after 1m0s
[15:38:34.161] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:88:0)
[15:38:34.161] Timer tick, asking Buildbucket for the build status
[15:38:34.679] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:34.679] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:89:0) after 1m0s
[15:39:34.814] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:89:0)
[15:39:34.814] Timer tick, asking Buildbucket for the build status
[15:39:35.116] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:35.116] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:90:0) after 1m0s
[15:40:35.160] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:90:0)
[15:40:35.160] Timer tick, asking Buildbucket for the build status
[15:40:35.329] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:35.329] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:91:0) after 1m0s
[15:41:35.441] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:91:0)
[15:41:35.441] Timer tick, asking Buildbucket for the build status
[15:41:35.821] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:35.821] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:92:0) after 1m0s
[15:42:35.797] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:92:0)
[15:42:35.797] Timer tick, asking Buildbucket for the build status
[15:42:36.201] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:36.201] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:93:0) after 1m0s
[15:43:36.194] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:93:0)
[15:43:36.194] Timer tick, asking Buildbucket for the build status
[15:43:36.383] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:36.383] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:94:0) after 1m0s
[15:44:36.399] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:94:0)
[15:44:36.399] Timer tick, asking Buildbucket for the build status
[15:44:36.586] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:36.586] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:95:0) after 1m0s
[15:45:36.678] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:95:0)
[15:45:36.678] Timer tick, asking Buildbucket for the build status
[15:45:37.644] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:37.644] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:96:0) after 1m0s
[15:46:37.671] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:96:0)
[15:46:37.672] Timer tick, asking Buildbucket for the build status
[15:46:37.904] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:37.904] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:97:0) after 1m0s
[15:47:37.996] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:97:0)
[15:47:37.996] Timer tick, asking Buildbucket for the build status
[15:47:38.600] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:38.600] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:98:0) after 1m0s
[15:48:38.631] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:98:0)
[15:48:38.631] Timer tick, asking Buildbucket for the build status
[15:48:38.822] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:38.822] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:99:0) after 1m0s
[15:49:38.865] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:99:0)
[15:49:38.865] Timer tick, asking Buildbucket for the build status
[15:49:39.259] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:39.259] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:100:0) after 1m0s
[15:50:39.356] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:100:0)
[15:50:39.356] Timer tick, asking Buildbucket for the build status
[15:50:39.490] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:39.490] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:101:0) after 1m0s
[15:51:39.521] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:101:0)
[15:51:39.521] Timer tick, asking Buildbucket for the build status
[15:51:39.757] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:39.757] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:102:0) after 1m0s
[15:52:39.788] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:102:0)
[15:52:39.788] Timer tick, asking Buildbucket for the build status
[15:52:39.962] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:39.962] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:103:0) after 1m0s
[15:53:40.200] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:103:0)
[15:53:40.200] Timer tick, asking Buildbucket for the build status
[15:53:40.563] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:40.563] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:104:0) after 1m0s
[15:54:40.650] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:104:0)
[15:54:40.650] Timer tick, asking Buildbucket for the build status
[15:54:41.271] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:41.271] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:105:0) after 1m0s
[15:55:41.354] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:105:0)
[15:55:41.354] Timer tick, asking Buildbucket for the build status
[15:55:42.232] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:42.232] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:106:0) after 1m0s
[15:56:42.339] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:106:0)
[15:56:42.339] Timer tick, asking Buildbucket for the build status
[15:56:42.528] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:42.528] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:107:0) after 1m0s
[15:57:42.635] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:107:0)
[15:57:42.635] Timer tick, asking Buildbucket for the build status
[15:57:43.418] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:43.418] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:108:0) after 1m0s
[15:58:43.497] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:108:0)
[15:58:43.497] Timer tick, asking Buildbucket for the build status
[15:58:43.963] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:43.963] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:109:0) after 1m0s
[15:59:44.099] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:109:0)
[15:59:44.099] Timer tick, asking Buildbucket for the build status
[15:59:44.270] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:44.270] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:110:0) after 1m0s
[16:00:44.488] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:110:0)
[16:00:44.488] Timer tick, asking Buildbucket for the build status
[16:00:44.679] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:44.679] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:111:0) after 1m0s
[16:01:44.760] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:111:0)
[16:01:44.760] Timer tick, asking Buildbucket for the build status
[16:01:45.389] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:45.389] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:112:0) after 1m0s
[16:02:45.464] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:112:0)
[16:02:45.464] Timer tick, asking Buildbucket for the build status
[16:02:46.106] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:46.106] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:113:0) after 1m0s
[16:03:46.220] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:113:0)
[16:03:46.220] Timer tick, asking Buildbucket for the build status
[16:03:46.425] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:46.425] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:114:0) after 1m0s
[16:04:46.513] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:114:0)
[16:04:46.513] Timer tick, asking Buildbucket for the build status
[16:04:47.370] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:47.370] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:115:0) after 1m0s
[16:05:47.466] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:115:0)
[16:05:47.466] Timer tick, asking Buildbucket for the build status
[16:05:47.722] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:47.722] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:116:0) after 1m0s
[16:06:47.829] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:116:0)
[16:06:47.829] Timer tick, asking Buildbucket for the build status
[16:06:47.961] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:47.961] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:117:0) after 1m0s
[16:07:47.970] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:117:0)
[16:07:47.970] Timer tick, asking Buildbucket for the build status
[16:07:48.382] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:48.382] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:118:0) after 1m0s
[16:08:48.483] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:118:0)
[16:08:48.483] Timer tick, asking Buildbucket for the build status
[16:08:48.642] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:48.643] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:119:0) after 1m0s
[16:09:48.723] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:119:0)
[16:09:48.723] Timer tick, asking Buildbucket for the build status
[16:09:48.899] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:48.899] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:120:0) after 1m0s
[16:10:49.015] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:120:0)
[16:10:49.015] Timer tick, asking Buildbucket for the build status
[16:10:49.423] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:49.423] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:121:0) after 1m0s
[16:11:49.508] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:121:0)
[16:11:49.508] Timer tick, asking Buildbucket for the build status
[16:11:49.795] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:49.795] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:122:0) after 1m0s
[16:12:49.880] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:122:0)
[16:12:49.880] Timer tick, asking Buildbucket for the build status
[16:12:50.058] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:50.058] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:123:0) after 1m0s
[16:13:50.107] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:123:0)
[16:13:50.107] Timer tick, asking Buildbucket for the build status
[16:13:50.275] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:50.275] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:124:0) after 1m0s
[16:14:50.388] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:124:0)
[16:14:50.388] Timer tick, asking Buildbucket for the build status
[16:14:50.554] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:50.554] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:125:0) after 1m0s
[16:15:50.578] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:125:0)
[16:15:50.578] Timer tick, asking Buildbucket for the build status
[16:15:50.991] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:50.991] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:126:0) after 1m0s
[16:16:51.161] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:126:0)
[16:16:51.161] Timer tick, asking Buildbucket for the build status
[16:16:51.456] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:51.456] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:127:0) after 1m0s
[16:17:51.526] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:127:0)
[16:17:51.526] Timer tick, asking Buildbucket for the build status
[16:17:51.701] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:51.701] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:128:0) after 1m0s
[16:18:51.792] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:128:0)
[16:18:51.792] Timer tick, asking Buildbucket for the build status
[16:18:51.950] Build 8893998475318386016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:51.950] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9059428537448803232:129:0) after 1m0s
[16:19:50.728] Received PubSub notification, asking Buildbucket for the build status
[16:19:51.044] Build 8893998475318386016: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[16:19:51.044] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1576426788531625",
"created_by": "project:chromium",
"created_ts": "1576419106140103",
"failure_reason": "BUILD_FAILURE",
"id": "8893998475318386016",
"parameters_json": "{\"builder_name\": \"ToTiOS\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1ed08d114236341db1bab4feeb48299d03b5d720\"}}",
"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\": \"build282-m9\", \"branch\": \"refs/heads/master\", \"buildername\": \"ToTiOS\", \"buildnumber\": 11556, \"got_angle_revision\": \"7587588595901010a7b8d8683d06796ae420edb8\", \"got_dawn_revision\": \"77fd4750ae5ad3ee9f281089fddfeaca56ed049c\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"1ed08d114236341db1bab4feeb48299d03b5d720\", \"got_revision_cp\": \"refs/heads/master@{#724972}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"fbbfeb700295d1fe1ec6419a93594e5e1540ea32\", \"got_v8_revision\": \"259dff3db0cc5126c95efc07addd5b380b93da48\", \"got_v8_revision_cp\": \"refs/heads/8.1.30@{#1}\", \"got_webrtc_revision\": \"17ea068e8c72ec8ee31002eb6c23cf453c090d60\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30091}\", \"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\", \"revision\": \"1ed08d114236341db1bab4feeb48299d03b5d720\", \"swarm_hashes\": {\"0\": \"63b4f8650a359be7463d924355046f484feff4d7\", \"1\": \"a6558a660d57b6b5c632a5c9ea8be08ea2aa1e27\", \"10\": \"1299f9e1e28209f5dffd62652e8d9e3c5ac584b7\", \"11\": \"bd3ecbec4862ce948ecc22431fb7a721b2c477d9\", \"12\": \"67b62203233e7f619c755ca596b46b6c67890e08\", \"13\": \"d10c6925fbdf58f331a710d5869e5d729390430a\", \"14\": \"839abe0ca91c8e04c25dd82926bb6cf20923d352\", \"15\": \"25bb1de2d30f51ba79338087cf5d692567d58ccf\", \"16\": \"afbe52cb36c3d0872b39b780895cf200ae513068\", \"2\": \"2b1a1c630916460f86b9106274d170b654f9f32c\", \"3\": \"0116550ae45ab5ced3cdb5900601c5027f148304\", \"4\": \"cbd5f16e97c3b6ca0a21c93813d13522baaf5303\", \"5\": \"00fbf00a955dad2692512bc52b75a1d6948a426a\", \"6\": \"a827406b1a69ce6da7193a742554cc82bbda512d\", \"7\": \"f115f6a3cec229a1f4ba88500753fdbcf12e2612\", \"8\": \"3b84b8468935da1c23d2ddfb883b54768c10550d\", \"9\": \"ff838506e1e024434dd50bf68499215f0ebd11de\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0c96c161cf7dd2e1c2b922319da904a0c0164c4adceed5a8844a45f1c516ad13_v2\", \"builder_4a4feefdd7e355061708c01720b06a0ca8c49ffc9968a2ebc674541738676b8f_v2\", \"builder_69caa34b8f39daac28a821cac2619730401d46a6479812cc34796e3bf180418a_v2\", \"builder_ae5debd901c74ee0906916cb62621bceb2a8b657b42c2e2e9e89a0f6a2e5e53b_v2\", \"builder_d42b89eab403033f5a2a8102ec9c436397350cedb439d5e09b11874a5784adb4_v2\", \"git\", \"goma_v2\", \"vpython\", \"xcode_ios_11a1027\"], \"cores\": [\"24\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-E5-2697_v2\"], \"gce\": [\"0\"], \"gpu\": [\"1002\", \"1002:679e\", \"1002:679e-4.0.11-3.2.8\"], \"hidpi\": [\"0\"], \"id\": [\"build282-m9\"], \"mac_model\": [\"MacPro6,1\"], \"machine_type\": [\"n1-standard-16\"], \"os\": [\"Mac\", \"Mac-10.14\", \"Mac-10.14.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.10\"], \"server_version\": [\"4724-8894a57\"], \"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": "1576419128383117",
"status": "COMPLETED",
"status_changed_ts": "1576419128946186",
"tags": [
"build_address:luci.chromium.ci/ToTiOS/11556",
"builder:ToTiOS",
"buildset:commit/git/1ed08d114236341db1bab4feeb48299d03b5d720",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1ed08d114236341db1bab4feeb48299d03b5d720",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059428537448803232",
"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/8893998475318386016/+/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:4922b8283b3b9510",
"user_agent:luci-scheduler"
],
"updated_ts": "1576426789209123",
"url": "https://ci.chromium.org/b/8893998475318386016",
"utcnow_ts": "1576426791034391"
}
}
[16:19:51.044] Invocation finished in 2h8m6.319127353s with status FAILED