[17:46:40.273] New invocation is queued and will start shortly
[17:46:41.664] Starting the invocation (attempt 1)
[17:46:41.708] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:46:41.708] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:46:41.708] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9070286651476784496",
"parameters_json": "{\"builder_name\":\"ToTiOS\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"d8594364a96d6d1ac8e75908054bac6820632cff\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:ToTiOS",
"scheduler_invocation_id:9070286651476784496",
"scheduler_job_id:chromium/ToTiOS",
"user_agent:luci-scheduler",
"buildset:commit/git/d8594364a96d6d1ac8e75908054bac6820632cff",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d8594364a96d6d1ac8e75908054bac6820632cff",
"gitiles_ref:refs/heads/master"
]
}
[17:46:43.177] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1566064002084853",
"id": "8904856588909178016",
"parameters_json": "{\"builder_name\": \"ToTiOS\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"d8594364a96d6d1ac8e75908054bac6820632cff\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1566064002891245",
"tags": [
"build_address:luci.chromium.ci/ToTiOS/9478",
"builder:ToTiOS",
"buildset:commit/git/d8594364a96d6d1ac8e75908054bac6820632cff",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d8594364a96d6d1ac8e75908054bac6820632cff",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9070286651476784496",
"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/8904856588909178016/+/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": "1566064002891382",
"url": "https://ci.chromium.org/b/8904856588909178016",
"utcnow_ts": "1566064003156154"
}
}
[17:46:43.177] Task URL: https://ci.chromium.org/b/8904856588909178016
[17:46:43.177] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:2:0) after 1m0s
[17:47:47.797] Received PubSub notification, asking Buildbucket for the build status
[17:47:48.240] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:53.630] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:2:0)
[17:47:53.630] Timer tick, asking Buildbucket for the build status
[17:47:53.790] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:53.790] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:4:0) after 1m0s
[17:48:53.855] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:4:0)
[17:48:53.855] Timer tick, asking Buildbucket for the build status
[17:48:54.013] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:54.013] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:5:0) after 1m0s
[17:49:54.046] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:5:0)
[17:49:54.046] Timer tick, asking Buildbucket for the build status
[17:49:54.221] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:54.221] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:6:0) after 1m0s
[17:50:54.294] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:6:0)
[17:50:54.294] Timer tick, asking Buildbucket for the build status
[17:50:54.437] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:54.437] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:7:0) after 1m0s
[17:51:54.464] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:7:0)
[17:51:54.464] Timer tick, asking Buildbucket for the build status
[17:51:54.758] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:54.759] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:8:0) after 1m0s
[17:52:54.789] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:8:0)
[17:52:54.789] Timer tick, asking Buildbucket for the build status
[17:52:55.889] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:55.890] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:9:0) after 1m0s
[17:53:55.869] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:9:0)
[17:53:55.869] Timer tick, asking Buildbucket for the build status
[17:53:56.310] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:56.310] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:10:0) after 1m0s
[17:54:56.344] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:10:0)
[17:54:56.344] Timer tick, asking Buildbucket for the build status
[17:54:56.497] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:56.497] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:11:0) after 1m0s
[17:55:56.530] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:11:0)
[17:55:56.530] Timer tick, asking Buildbucket for the build status
[17:55:57.489] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:57.489] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:12:0) after 1m0s
[17:56:57.476] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:12:0)
[17:56:57.476] Timer tick, asking Buildbucket for the build status
[17:56:58.007] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:58.007] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:13:0) after 1m0s
[17:57:58.013] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:13:0)
[17:57:58.013] Timer tick, asking Buildbucket for the build status
[17:57:58.192] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:58.192] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:14:0) after 1m0s
[17:58:58.241] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:14:0)
[17:58:58.241] Timer tick, asking Buildbucket for the build status
[17:58:58.428] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:58.428] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:15:0) after 1m0s
[17:59:58.457] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:15:0)
[17:59:58.457] Timer tick, asking Buildbucket for the build status
[17:59:58.906] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:58.906] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:16:0) after 1m0s
[18:00:58.933] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:16:0)
[18:00:58.933] Timer tick, asking Buildbucket for the build status
[18:00:59.122] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:59.122] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:17:0) after 1m0s
[18:01:59.166] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:17:0)
[18:01:59.166] Timer tick, asking Buildbucket for the build status
[18:01:59.354] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:59.354] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:18:0) after 1m0s
[18:02:59.375] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:18:0)
[18:02:59.375] Timer tick, asking Buildbucket for the build status
[18:03:00.270] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:00.270] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:19:0) after 1m0s
[18:04:00.363] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:19:0)
[18:04:00.363] Timer tick, asking Buildbucket for the build status
[18:04:01.178] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:01.178] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:20:0) after 1m0s
[18:05:01.202] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:20:0)
[18:05:01.202] Timer tick, asking Buildbucket for the build status
[18:05:01.931] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:01.931] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:21:0) after 1m0s
[18:06:01.980] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:21:0)
[18:06:01.980] Timer tick, asking Buildbucket for the build status
[18:06:02.209] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:02.209] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:22:0) after 1m0s
[18:07:02.246] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:22:0)
[18:07:02.246] Timer tick, asking Buildbucket for the build status
[18:07:02.685] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:02.685] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:23:0) after 1m0s
[18:08:02.665] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:23:0)
[18:08:02.665] Timer tick, asking Buildbucket for the build status
[18:08:02.796] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:02.796] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:24:0) after 1m0s
[18:09:02.892] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:24:0)
[18:09:02.892] Timer tick, asking Buildbucket for the build status
[18:09:03.378] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:03.378] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:25:0) after 1m0s
[18:10:03.451] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:25:0)
[18:10:03.451] Timer tick, asking Buildbucket for the build status
[18:10:03.594] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:03.594] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:26:0) after 1m0s
[18:11:03.581] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:26:0)
[18:11:03.581] Timer tick, asking Buildbucket for the build status
[18:11:03.750] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:03.750] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:27:0) after 1m0s
[18:12:03.804] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:27:0)
[18:12:03.804] Timer tick, asking Buildbucket for the build status
[18:12:04.359] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:04.359] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:28:0) after 1m0s
[18:13:04.467] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:28:0)
[18:13:04.467] Timer tick, asking Buildbucket for the build status
[18:13:04.673] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:04.673] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:29:0) after 1m0s
[18:14:04.695] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:29:0)
[18:14:04.695] Timer tick, asking Buildbucket for the build status
[18:14:05.013] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:05.013] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:30:0) after 1m0s
[18:15:05.089] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:30:0)
[18:15:05.089] Timer tick, asking Buildbucket for the build status
[18:15:05.638] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:05.638] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:31:0) after 1m0s
[18:16:05.700] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:31:0)
[18:16:05.700] Timer tick, asking Buildbucket for the build status
[18:16:05.923] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:05.923] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:32:0) after 1m0s
[18:17:06.018] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:32:0)
[18:17:06.018] Timer tick, asking Buildbucket for the build status
[18:17:06.289] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:06.289] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:33:0) after 1m0s
[18:18:06.312] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:33:0)
[18:18:06.312] Timer tick, asking Buildbucket for the build status
[18:18:06.496] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:06.496] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:34:0) after 1m0s
[18:19:06.551] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:34:0)
[18:19:06.551] Timer tick, asking Buildbucket for the build status
[18:19:06.699] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:06.699] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:35:0) after 1m0s
[18:20:06.722] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:35:0)
[18:20:06.722] Timer tick, asking Buildbucket for the build status
[18:20:07.050] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:07.051] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:36:0) after 1m0s
[18:21:07.092] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:36:0)
[18:21:07.092] Timer tick, asking Buildbucket for the build status
[18:21:07.342] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:07.342] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:37:0) after 1m0s
[18:22:07.388] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:37:0)
[18:22:07.388] Timer tick, asking Buildbucket for the build status
[18:22:07.744] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:07.744] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:38:0) after 1m0s
[18:23:07.769] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:38:0)
[18:23:07.769] Timer tick, asking Buildbucket for the build status
[18:23:08.215] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:08.215] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:39:0) after 1m0s
[18:24:08.241] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:39:0)
[18:24:08.241] Timer tick, asking Buildbucket for the build status
[18:24:08.458] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:08.458] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:40:0) after 1m0s
[18:25:08.498] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:40:0)
[18:25:08.498] Timer tick, asking Buildbucket for the build status
[18:25:08.658] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:08.658] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:41:0) after 1m0s
[18:26:08.687] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:41:0)
[18:26:08.687] Timer tick, asking Buildbucket for the build status
[18:26:09.106] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:09.106] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:42:0) after 1m0s
[18:27:09.136] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:42:0)
[18:27:09.136] Timer tick, asking Buildbucket for the build status
[18:27:09.687] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:09.687] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:43:0) after 1m0s
[18:28:09.711] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:43:0)
[18:28:09.711] Timer tick, asking Buildbucket for the build status
[18:28:09.999] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:09.999] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:44:0) after 1m0s
[18:29:10.038] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:44:0)
[18:29:10.038] Timer tick, asking Buildbucket for the build status
[18:29:10.894] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:10.894] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:45:0) after 1m0s
[18:30:10.916] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:45:0)
[18:30:10.916] Timer tick, asking Buildbucket for the build status
[18:30:11.274] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:11.274] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:46:0) after 1m0s
[18:31:11.297] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:46:0)
[18:31:11.297] Timer tick, asking Buildbucket for the build status
[18:31:11.826] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:11.826] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:47:0) after 1m0s
[18:32:12.389] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:47:0)
[18:32:12.389] Timer tick, asking Buildbucket for the build status
[18:32:13.128] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:13.128] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:48:0) after 1m0s
[18:33:13.388] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:48:0)
[18:33:13.388] Timer tick, asking Buildbucket for the build status
[18:33:13.666] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:13.666] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:49:0) after 1m0s
[18:34:14.080] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:49:0)
[18:34:14.080] Timer tick, asking Buildbucket for the build status
[18:34:14.226] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:14.226] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:50:0) after 1m0s
[18:35:14.643] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:50:0)
[18:35:14.643] Timer tick, asking Buildbucket for the build status
[18:35:15.325] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:15.325] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:51:0) after 1m0s
[18:36:15.574] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:51:0)
[18:36:15.574] Timer tick, asking Buildbucket for the build status
[18:36:16.250] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:16.250] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:52:0) after 1m0s
[18:37:16.417] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:52:0)
[18:37:16.417] Timer tick, asking Buildbucket for the build status
[18:37:16.721] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:16.721] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:53:0) after 1m0s
[18:38:17.085] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:53:0)
[18:38:17.085] Timer tick, asking Buildbucket for the build status
[18:38:17.277] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:17.277] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:54:0) after 1m0s
[18:39:17.306] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:54:0)
[18:39:17.306] Timer tick, asking Buildbucket for the build status
[18:39:17.782] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:17.782] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:55:0) after 1m0s
[18:40:17.837] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:55:0)
[18:40:17.837] Timer tick, asking Buildbucket for the build status
[18:40:18.309] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:18.309] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:56:0) after 1m0s
[18:41:18.518] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:56:0)
[18:41:18.518] Timer tick, asking Buildbucket for the build status
[18:41:18.804] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:18.804] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:57:0) after 1m0s
[18:42:18.970] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:57:0)
[18:42:18.970] Timer tick, asking Buildbucket for the build status
[18:42:19.173] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:19.173] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:58:0) after 1m0s
[18:43:19.325] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:58:0)
[18:43:19.325] Timer tick, asking Buildbucket for the build status
[18:43:19.763] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:19.764] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:59:0) after 1m0s
[18:44:19.854] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:59:0)
[18:44:19.854] Timer tick, asking Buildbucket for the build status
[18:44:20.046] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:20.046] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:60:0) after 1m0s
[18:45:20.090] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:60:0)
[18:45:20.090] Timer tick, asking Buildbucket for the build status
[18:45:20.273] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:20.273] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:61:0) after 1m0s
[18:46:20.317] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:61:0)
[18:46:20.317] Timer tick, asking Buildbucket for the build status
[18:46:20.615] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:20.615] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:62:0) after 1m0s
[18:47:20.618] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:62:0)
[18:47:20.618] Timer tick, asking Buildbucket for the build status
[18:47:20.811] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:20.812] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:63:0) after 1m0s
[18:48:20.833] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:63:0)
[18:48:20.833] Timer tick, asking Buildbucket for the build status
[18:48:21.197] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:21.197] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:64:0) after 1m0s
[18:49:21.227] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:64:0)
[18:49:21.227] Timer tick, asking Buildbucket for the build status
[18:49:21.379] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:21.379] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:65:0) after 1m0s
[18:50:21.403] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:65:0)
[18:50:21.403] Timer tick, asking Buildbucket for the build status
[18:50:21.921] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:21.921] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:66:0) after 1m0s
[18:51:21.958] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:66:0)
[18:51:21.958] Timer tick, asking Buildbucket for the build status
[18:51:24.589] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:24.589] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:67:0) after 1m0s
[18:52:31.262] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:67:0)
[18:52:31.262] Timer tick, asking Buildbucket for the build status
[18:52:31.436] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:31.436] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:68:0) after 1m0s
[18:53:31.469] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:68:0)
[18:53:31.469] Timer tick, asking Buildbucket for the build status
[18:53:31.624] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:31.624] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:69:0) after 1m0s
[18:54:31.701] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:69:0)
[18:54:31.701] Timer tick, asking Buildbucket for the build status
[18:54:31.840] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:31.840] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:70:0) after 1m0s
[18:55:31.887] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:70:0)
[18:55:31.887] Timer tick, asking Buildbucket for the build status
[18:55:32.053] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:32.053] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:71:0) after 1m0s
[18:56:32.157] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:71:0)
[18:56:32.157] Timer tick, asking Buildbucket for the build status
[18:56:32.324] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:32.324] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:72:0) after 1m0s
[18:57:32.357] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:72:0)
[18:57:32.357] Timer tick, asking Buildbucket for the build status
[18:57:32.548] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:32.548] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:73:0) after 1m0s
[18:58:32.574] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:73:0)
[18:58:32.574] Timer tick, asking Buildbucket for the build status
[18:58:32.745] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:32.745] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:74:0) after 1m0s
[18:59:32.828] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:74:0)
[18:59:32.828] Timer tick, asking Buildbucket for the build status
[18:59:33.833] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:33.833] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:75:0) after 1m0s
[19:00:33.946] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:75:0)
[19:00:33.946] Timer tick, asking Buildbucket for the build status
[19:00:34.404] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:34.404] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:76:0) after 1m0s
[19:01:34.507] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:76:0)
[19:01:34.507] Timer tick, asking Buildbucket for the build status
[19:01:34.905] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:34.905] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:77:0) after 1m0s
[19:02:35.016] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:77:0)
[19:02:35.016] Timer tick, asking Buildbucket for the build status
[19:02:35.218] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:35.219] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:78:0) after 1m0s
[19:03:35.251] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:78:0)
[19:03:35.251] Timer tick, asking Buildbucket for the build status
[19:03:36.271] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:36.271] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:79:0) after 1m0s
[19:04:36.304] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:79:0)
[19:04:36.304] Timer tick, asking Buildbucket for the build status
[19:04:36.746] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:36.746] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:80:0) after 1m0s
[19:05:36.804] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:80:0)
[19:05:36.804] Timer tick, asking Buildbucket for the build status
[19:05:38.746] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:38.746] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:81:0) after 1m0s
[19:06:38.816] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:81:0)
[19:06:38.816] Timer tick, asking Buildbucket for the build status
[19:06:40.030] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:40.030] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:82:0) after 1m0s
[19:07:40.113] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:82:0)
[19:07:40.113] Timer tick, asking Buildbucket for the build status
[19:07:41.094] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:41.094] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:83:0) after 1m0s
[19:08:41.156] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:83:0)
[19:08:41.156] Timer tick, asking Buildbucket for the build status
[19:08:42.055] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:42.055] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:84:0) after 1m0s
[19:09:42.225] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:84:0)
[19:09:42.225] Timer tick, asking Buildbucket for the build status
[19:09:42.391] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:42.391] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:85:0) after 1m0s
[19:10:42.414] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:85:0)
[19:10:42.414] Timer tick, asking Buildbucket for the build status
[19:10:43.227] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:43.227] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:86:0) after 1m0s
[19:11:43.259] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:86:0)
[19:11:43.259] Timer tick, asking Buildbucket for the build status
[19:11:43.648] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:43.648] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:87:0) after 1m0s
[19:12:43.673] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:87:0)
[19:12:43.673] Timer tick, asking Buildbucket for the build status
[19:12:44.189] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:44.189] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:88:0) after 1m0s
[19:13:44.252] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:88:0)
[19:13:44.252] Timer tick, asking Buildbucket for the build status
[19:13:44.521] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:44.521] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:89:0) after 1m0s
[19:14:44.624] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:89:0)
[19:14:44.624] Timer tick, asking Buildbucket for the build status
[19:14:44.813] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:44.813] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:90:0) after 1m0s
[19:15:44.848] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:90:0)
[19:15:44.848] Timer tick, asking Buildbucket for the build status
[19:15:45.617] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:45.617] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:91:0) after 1m0s
[19:16:45.698] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:91:0)
[19:16:45.698] Timer tick, asking Buildbucket for the build status
[19:16:45.910] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:45.910] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:92:0) after 1m0s
[19:17:45.945] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:92:0)
[19:17:45.945] Timer tick, asking Buildbucket for the build status
[19:17:49.643] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:49.643] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:93:0) after 1m0s
[19:18:49.641] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:93:0)
[19:18:49.641] Timer tick, asking Buildbucket for the build status
[19:18:49.807] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:49.808] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:94:0) after 1m0s
[19:19:49.835] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:94:0)
[19:19:49.835] Timer tick, asking Buildbucket for the build status
[19:19:50.004] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:50.005] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:95:0) after 1m0s
[19:20:50.064] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:95:0)
[19:20:50.064] Timer tick, asking Buildbucket for the build status
[19:20:50.591] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:50.591] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:96:0) after 1m0s
[19:21:50.667] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:96:0)
[19:21:50.667] Timer tick, asking Buildbucket for the build status
[19:21:51.098] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:51.098] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:97:0) after 1m0s
[19:22:51.128] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:97:0)
[19:22:51.128] Timer tick, asking Buildbucket for the build status
[19:22:51.432] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:51.432] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:98:0) after 1m0s
[19:23:51.446] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:98:0)
[19:23:51.446] Timer tick, asking Buildbucket for the build status
[19:23:51.742] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:51.742] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:99:0) after 1m0s
[19:24:51.816] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:99:0)
[19:24:51.816] Timer tick, asking Buildbucket for the build status
[19:24:51.967] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:51.967] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:100:0) after 1m0s
[19:25:52.107] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:100:0)
[19:25:52.107] Timer tick, asking Buildbucket for the build status
[19:25:52.596] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:52.596] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:101:0) after 1m0s
[19:26:52.631] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:101:0)
[19:26:52.631] Timer tick, asking Buildbucket for the build status
[19:26:52.829] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:52.829] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:102:0) after 1m0s
[19:27:52.859] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:102:0)
[19:27:52.859] Timer tick, asking Buildbucket for the build status
[19:27:53.666] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:53.666] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:103:0) after 1m0s
[19:28:54.089] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:103:0)
[19:28:54.089] Timer tick, asking Buildbucket for the build status
[19:28:54.263] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:54.263] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:104:0) after 1m0s
[19:29:54.422] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:104:0)
[19:29:54.422] Timer tick, asking Buildbucket for the build status
[19:29:55.296] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:55.296] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:105:0) after 1m0s
[19:30:55.561] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:105:0)
[19:30:55.562] Timer tick, asking Buildbucket for the build status
[19:30:55.794] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:55.794] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:106:0) after 1m0s
[19:31:56.014] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:106:0)
[19:31:56.014] Timer tick, asking Buildbucket for the build status
[19:31:56.844] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:56.844] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:107:0) after 1m0s
[19:32:56.873] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:107:0)
[19:32:56.873] Timer tick, asking Buildbucket for the build status
[19:32:57.283] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:57.283] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:108:0) after 1m0s
[19:33:57.318] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:108:0)
[19:33:57.318] Timer tick, asking Buildbucket for the build status
[19:33:58.713] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:58.713] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:109:0) after 1m0s
[19:34:58.747] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:109:0)
[19:34:58.747] Timer tick, asking Buildbucket for the build status
[19:34:59.026] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:59.026] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:110:0) after 1m0s
[19:35:59.056] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:110:0)
[19:35:59.056] Timer tick, asking Buildbucket for the build status
[19:35:59.631] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:59.631] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:111:0) after 1m0s
[19:36:59.657] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:111:0)
[19:36:59.657] Timer tick, asking Buildbucket for the build status
[19:37:00.292] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:00.292] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:112:0) after 1m0s
[19:38:00.367] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:112:0)
[19:38:00.367] Timer tick, asking Buildbucket for the build status
[19:38:00.822] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:00.822] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:113:0) after 1m0s
[19:39:00.851] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:113:0)
[19:39:00.851] Timer tick, asking Buildbucket for the build status
[19:39:01.058] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:01.058] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:114:0) after 1m0s
[19:40:01.114] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:114:0)
[19:40:01.115] Timer tick, asking Buildbucket for the build status
[19:40:01.827] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:01.828] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:115:0) after 1m0s
[19:41:01.823] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:115:0)
[19:41:01.823] Timer tick, asking Buildbucket for the build status
[19:41:02.000] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:02.000] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:116:0) after 1m0s
[19:42:02.137] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:116:0)
[19:42:02.137] Timer tick, asking Buildbucket for the build status
[19:42:02.343] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:02.343] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:117:0) after 1m0s
[19:43:02.461] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:117:0)
[19:43:02.461] Timer tick, asking Buildbucket for the build status
[19:43:03.022] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:03.022] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:118:0) after 1m0s
[19:44:03.165] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:118:0)
[19:44:03.165] Timer tick, asking Buildbucket for the build status
[19:44:03.319] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:03.320] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:119:0) after 1m0s
[19:45:03.349] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:119:0)
[19:45:03.349] Timer tick, asking Buildbucket for the build status
[19:45:03.804] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:03.804] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:120:0) after 1m0s
[19:46:03.941] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:120:0)
[19:46:03.941] Timer tick, asking Buildbucket for the build status
[19:46:04.176] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:04.176] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:121:0) after 1m0s
[19:47:04.378] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:121:0)
[19:47:04.378] Timer tick, asking Buildbucket for the build status
[19:47:04.552] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:04.552] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:122:0) after 1m0s
[19:48:04.864] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:122:0)
[19:48:04.864] Timer tick, asking Buildbucket for the build status
[19:48:05.465] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:05.465] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:123:0) after 1m0s
[19:49:05.602] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:123:0)
[19:49:05.602] Timer tick, asking Buildbucket for the build status
[19:49:07.814] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:07.814] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:124:0) after 1m0s
[19:50:08.057] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:124:0)
[19:50:08.058] Timer tick, asking Buildbucket for the build status
[19:50:08.426] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:08.426] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:125:0) after 1m0s
[19:51:08.643] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:125:0)
[19:51:08.643] Timer tick, asking Buildbucket for the build status
[19:51:10.047] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:10.047] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:126:0) after 1m0s
[19:52:10.248] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:126:0)
[19:52:10.248] Timer tick, asking Buildbucket for the build status
[19:52:11.075] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:11.075] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:127:0) after 1m0s
[19:53:11.098] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:127:0)
[19:53:11.098] Timer tick, asking Buildbucket for the build status
[19:53:11.274] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:11.274] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:128:0) after 1m0s
[19:54:11.657] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:128:0)
[19:54:11.657] Timer tick, asking Buildbucket for the build status
[19:54:12.728] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:12.728] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:129:0) after 1m0s
[19:55:14.072] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:129:0)
[19:55:14.072] Timer tick, asking Buildbucket for the build status
[19:55:14.526] Build 8904856588909178016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:14.526] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070286651476784496:130:0) after 1m0s
[19:55:19.681] Received PubSub notification, asking Buildbucket for the build status
[19:55:19.789] Build 8904856588909178016: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[19:55:19.789] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1566071719161540",
"created_by": "project:chromium",
"created_ts": "1566064002084853",
"failure_reason": "BUILD_FAILURE",
"id": "8904856588909178016",
"parameters_json": "{\"builder_name\": \"ToTiOS\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"d8594364a96d6d1ac8e75908054bac6820632cff\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Failed ios_web_inttests (iPhone 6s iOS 12.1).\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"build286-m9\", \"branch\": \"refs/heads/master\", \"buildername\": \"ToTiOS\", \"buildnumber\": 9478, \"got_angle_revision\": \"265fdf0c5fd6b1e28a54c18ca40a8461c7f449a7\", \"got_dawn_revision\": \"ee3de1e1f3da2386197a2c586304deb263890a35\", \"got_nacl_revision\": \"058a26f57015051953a727add26e7e26348b9558\", \"got_revision\": \"d8594364a96d6d1ac8e75908054bac6820632cff\", \"got_revision_cp\": \"refs/heads/master@{#687950}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"7b861aca66ea8080a32eba6217f8833cdcc32a09\", \"got_v8_revision_cp\": \"refs/heads/7.8.113@{#1}\", \"got_webrtc_revision\": \"f5e5d250bc85616e74487f8330ed8d99cc9cf84f\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28880}\", \"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\": \"d8594364a96d6d1ac8e75908054bac6820632cff\", \"swarm_hashes\": {\"0\": \"da1fb383c1e3f1d06e2b926e2b5786144b8b18db\", \"1\": \"9b3eef74314c2877159ea9e3f5b2974bc35a9010\", \"10\": \"39382b33d0a0d903b982238aeab7e34a6640367f\", \"11\": \"0b9964fc1c0d99d95ec445b7548f4008571ea3d2\", \"12\": \"5d9e8689aab05a3be10a45a3f4641651cbef3d4f\", \"13\": \"b64ec0b0c6201c7ed072a0acaff69a135153106e\", \"14\": \"eceb4a17b615829d27be7515a359d9931876630b\", \"15\": \"00aaf149d92e740c3b145a9194c5c574f5038272\", \"16\": \"a80cf1584be8a0d30726f50918dccc9c507f2b84\", \"2\": \"db7388be9311447d5c9671c4dfd2d274d37041aa\", \"3\": \"cf30285130f5af434f5d807c921ec50b634db850\", \"4\": \"2a3efd6a5b0f51364fea0100f1b4ca3b9d5bdd41\", \"5\": \"132bcac6ecb3585e35d2128f7785339c2ae21384\", \"6\": \"96e00059272c4b4b03d5b1dbca5823077c7c4efe\", \"7\": \"4b7ebf0d26f19b7dae84f3775bfa797a9c6aca6f\", \"8\": \"3dc9406b5ebe2af2560eacaaa2f6d10157026f9b\", \"9\": \"f7b01ba70e97489add4ab85047487e62b914f591\"}}, \"ui\": {\"info\": \"Failed ios_web_inttests (iPhone 6s iOS 12.1).\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1566064040339615",
"status": "COMPLETED",
"status_changed_ts": "1566064002891245",
"tags": [
"build_address:luci.chromium.ci/ToTiOS/9478",
"builder:ToTiOS",
"buildset:commit/git/d8594364a96d6d1ac8e75908054bac6820632cff",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d8594364a96d6d1ac8e75908054bac6820632cff",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9070286651476784496",
"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/8904856588909178016/+/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:46b981d71b85f310",
"user_agent:luci-scheduler"
],
"updated_ts": "1566071719414103",
"url": "https://ci.chromium.org/b/8904856588909178016",
"utcnow_ts": "1566071719780864"
}
}
[19:55:19.791] Invocation finished in 2h8m39.538770532s with status FAILED