[21:21:29.822] New invocation is queued and will start shortly
[21:21:31.288] Starting the invocation (attempt 1)
[21:21:31.367] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:21:31.367] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:21:31.367] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9067374032881616592",
"parameters_json": "{\"builder_name\":\"ToTiOS\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"ad8820a6cfe9b22fa9530ba88681cf3865b36e58\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:ToTiOS",
"scheduler_invocation_id:9067374032881616592",
"scheduler_job_id:chromium/ToTiOS",
"user_agent:luci-scheduler",
"buildset:commit/git/ad8820a6cfe9b22fa9530ba88681cf3865b36e58",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ad8820a6cfe9b22fa9530ba88681cf3865b36e58",
"gitiles_ref:refs/heads/master"
]
}
[21:21:32.616] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1568841691587099",
"id": "8901943970360666992",
"parameters_json": "{\"builder_name\": \"ToTiOS\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"ad8820a6cfe9b22fa9530ba88681cf3865b36e58\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1568841692159183",
"tags": [
"build_address:luci.chromium.ci/ToTiOS/9958",
"builder:ToTiOS",
"buildset:commit/git/ad8820a6cfe9b22fa9530ba88681cf3865b36e58",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ad8820a6cfe9b22fa9530ba88681cf3865b36e58",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9067374032881616592",
"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/8901943970360666992/+/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": "1568841692159286",
"url": "https://ci.chromium.org/b/8901943970360666992",
"utcnow_ts": "1568841692597751"
}
}
[21:21:32.616] Task URL: https://ci.chromium.org/b/8901943970360666992
[21:21:32.618] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:2:0) after 1m0s
[21:21:41.286] Received PubSub notification, asking Buildbucket for the build status
[21:21:41.960] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:32.649] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:2:0)
[21:22:32.649] Timer tick, asking Buildbucket for the build status
[21:22:33.292] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:33.292] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:4:0) after 1m0s
[21:23:33.448] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:4:0)
[21:23:33.448] Timer tick, asking Buildbucket for the build status
[21:23:34.453] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:34.453] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:5:0) after 1m0s
[21:24:34.513] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:5:0)
[21:24:34.513] Timer tick, asking Buildbucket for the build status
[21:24:35.740] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:35.740] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:6:0) after 1m0s
[21:25:35.813] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:6:0)
[21:25:35.813] Timer tick, asking Buildbucket for the build status
[21:25:36.452] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:36.452] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:7:0) after 1m0s
[21:26:36.511] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:7:0)
[21:26:36.511] Timer tick, asking Buildbucket for the build status
[21:26:37.286] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:37.286] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:8:0) after 1m0s
[21:27:37.276] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:8:0)
[21:27:37.276] Timer tick, asking Buildbucket for the build status
[21:27:38.053] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:38.053] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:9:0) after 1m0s
[21:28:38.120] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:9:0)
[21:28:38.120] Timer tick, asking Buildbucket for the build status
[21:28:38.496] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:38.496] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:10:0) after 1m0s
[21:29:38.519] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:10:0)
[21:29:38.519] Timer tick, asking Buildbucket for the build status
[21:29:38.739] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:38.739] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:11:0) after 1m0s
[21:30:38.804] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:11:0)
[21:30:38.804] Timer tick, asking Buildbucket for the build status
[21:30:39.039] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:39.039] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:12:0) after 1m0s
[21:31:39.133] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:12:0)
[21:31:39.133] Timer tick, asking Buildbucket for the build status
[21:31:40.184] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:40.185] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:13:0) after 1m0s
[21:32:40.309] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:13:0)
[21:32:40.309] Timer tick, asking Buildbucket for the build status
[21:32:40.779] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:40.779] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:14:0) after 1m0s
[21:33:40.919] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:14:0)
[21:33:40.919] Timer tick, asking Buildbucket for the build status
[21:33:41.153] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:41.153] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:15:0) after 1m0s
[21:34:41.183] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:15:0)
[21:34:41.183] Timer tick, asking Buildbucket for the build status
[21:34:41.356] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:41.356] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:16:0) after 1m0s
[21:35:41.523] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:16:0)
[21:35:41.523] Timer tick, asking Buildbucket for the build status
[21:35:41.850] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:41.850] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:17:0) after 1m0s
[21:36:41.938] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:17:0)
[21:36:41.938] Timer tick, asking Buildbucket for the build status
[21:36:42.644] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:42.644] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:18:0) after 1m0s
[21:37:42.636] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:18:0)
[21:37:42.636] Timer tick, asking Buildbucket for the build status
[21:37:43.494] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:43.494] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:19:0) after 1m0s
[21:38:43.550] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:19:0)
[21:38:43.550] Timer tick, asking Buildbucket for the build status
[21:38:43.958] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:43.958] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:20:0) after 1m0s
[21:39:43.982] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:20:0)
[21:39:43.982] Timer tick, asking Buildbucket for the build status
[21:39:44.471] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:44.471] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:21:0) after 1m0s
[21:40:44.511] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:21:0)
[21:40:44.511] Timer tick, asking Buildbucket for the build status
[21:40:44.676] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:44.676] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:22:0) after 1m0s
[21:41:44.810] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:22:0)
[21:41:44.810] Timer tick, asking Buildbucket for the build status
[21:41:45.152] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:45.152] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:23:0) after 1m0s
[21:42:45.407] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:23:0)
[21:42:45.407] Timer tick, asking Buildbucket for the build status
[21:42:46.426] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:46.426] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:24:0) after 1m0s
[21:43:46.523] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:24:0)
[21:43:46.523] Timer tick, asking Buildbucket for the build status
[21:43:47.720] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:47.720] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:25:0) after 1m0s
[21:44:47.788] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:25:0)
[21:44:47.788] Timer tick, asking Buildbucket for the build status
[21:44:48.983] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:48.983] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:26:0) after 1m0s
[21:45:49.285] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:26:0)
[21:45:49.285] Timer tick, asking Buildbucket for the build status
[21:45:50.479] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:50.479] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:27:0) after 1m0s
[21:46:50.637] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:27:0)
[21:46:50.637] Timer tick, asking Buildbucket for the build status
[21:46:51.757] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:51.757] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:28:0) after 1m0s
[21:47:51.921] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:28:0)
[21:47:51.921] Timer tick, asking Buildbucket for the build status
[21:47:53.421] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:53.421] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:29:0) after 1m0s
[21:48:53.498] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:29:0)
[21:48:53.498] Timer tick, asking Buildbucket for the build status
[21:48:58.137] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:58.137] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:30:0) after 1m0s
[21:49:58.173] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:30:0)
[21:49:58.173] Timer tick, asking Buildbucket for the build status
[21:49:58.997] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:58.997] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:31:0) after 1m0s
[21:50:59.097] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:31:0)
[21:50:59.097] Timer tick, asking Buildbucket for the build status
[21:50:59.522] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:59.522] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:32:0) after 1m0s
[21:51:59.698] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:32:0)
[21:51:59.698] Timer tick, asking Buildbucket for the build status
[21:51:59.976] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:59.976] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:33:0) after 1m0s
[21:53:00.104] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:33:0)
[21:53:00.104] Timer tick, asking Buildbucket for the build status
[21:53:00.970] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:00.970] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:34:0) after 1m0s
[21:54:00.999] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:34:0)
[21:54:00.999] Timer tick, asking Buildbucket for the build status
[21:54:02.204] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:02.204] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:35:0) after 1m0s
[21:55:02.499] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:35:0)
[21:55:02.499] Timer tick, asking Buildbucket for the build status
[21:55:03.574] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:03.574] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:36:0) after 1m0s
[21:56:03.613] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:36:0)
[21:56:03.613] Timer tick, asking Buildbucket for the build status
[21:56:03.913] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:03.913] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:37:0) after 1m0s
[21:57:03.942] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:37:0)
[21:57:03.942] Timer tick, asking Buildbucket for the build status
[21:57:04.077] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:04.077] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:38:0) after 1m0s
[21:58:04.267] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:38:0)
[21:58:04.267] Timer tick, asking Buildbucket for the build status
[21:58:04.461] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:04.461] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:39:0) after 1m0s
[21:59:04.648] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:39:0)
[21:59:04.648] Timer tick, asking Buildbucket for the build status
[21:59:04.981] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:04.981] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:40:0) after 1m0s
[22:00:05.077] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:40:0)
[22:00:05.077] Timer tick, asking Buildbucket for the build status
[22:00:06.251] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:06.251] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:41:0) after 1m0s
[22:01:06.534] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:41:0)
[22:01:06.534] Timer tick, asking Buildbucket for the build status
[22:01:06.817] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:06.817] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:42:0) after 1m0s
[22:02:06.968] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:42:0)
[22:02:06.968] Timer tick, asking Buildbucket for the build status
[22:02:07.602] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:07.602] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:43:0) after 1m0s
[22:03:07.637] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:43:0)
[22:03:07.637] Timer tick, asking Buildbucket for the build status
[22:03:12.998] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:12.998] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:44:0) after 1m0s
[22:04:12.981] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:44:0)
[22:04:12.981] Timer tick, asking Buildbucket for the build status
[22:04:14.414] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:14.414] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:45:0) after 1m0s
[22:05:14.502] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:45:0)
[22:05:14.502] Timer tick, asking Buildbucket for the build status
[22:05:15.471] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:15.471] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:46:0) after 1m0s
[22:06:15.753] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:46:0)
[22:06:15.753] Timer tick, asking Buildbucket for the build status
[22:06:15.962] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:15.962] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:47:0) after 1m0s
[22:07:16.033] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:47:0)
[22:07:16.033] Timer tick, asking Buildbucket for the build status
[22:07:16.328] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:16.328] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:48:0) after 1m0s
[22:08:16.554] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:48:0)
[22:08:16.554] Timer tick, asking Buildbucket for the build status
[22:08:16.737] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:16.737] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:49:0) after 1m0s
[22:09:16.990] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:49:0)
[22:09:16.990] Timer tick, asking Buildbucket for the build status
[22:09:18.526] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:18.526] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:50:0) after 1m0s
[22:10:18.621] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:50:0)
[22:10:18.621] Timer tick, asking Buildbucket for the build status
[22:10:18.941] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:18.941] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:51:0) after 1m0s
[22:11:19.033] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:51:0)
[22:11:19.033] Timer tick, asking Buildbucket for the build status
[22:11:19.636] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:19.636] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:52:0) after 1m0s
[22:12:19.716] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:52:0)
[22:12:19.716] Timer tick, asking Buildbucket for the build status
[22:12:22.858] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:22.858] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:53:0) after 1m0s
[22:13:23.024] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:53:0)
[22:13:23.024] Timer tick, asking Buildbucket for the build status
[22:13:23.223] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:23.223] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:54:0) after 1m0s
[22:14:23.398] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:54:0)
[22:14:23.398] Timer tick, asking Buildbucket for the build status
[22:14:23.912] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:23.912] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:55:0) after 1m0s
[22:15:23.988] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:55:0)
[22:15:23.988] Timer tick, asking Buildbucket for the build status
[22:15:24.274] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:24.274] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:56:0) after 1m0s
[22:16:24.374] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:56:0)
[22:16:24.374] Timer tick, asking Buildbucket for the build status
[22:16:24.652] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:24.652] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:57:0) after 1m0s
[22:17:24.736] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:57:0)
[22:17:24.736] Timer tick, asking Buildbucket for the build status
[22:17:25.507] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:25.507] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:58:0) after 1m0s
[22:18:25.524] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:58:0)
[22:18:25.524] Timer tick, asking Buildbucket for the build status
[22:18:25.833] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:25.833] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:59:0) after 1m0s
[22:19:26.230] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:59:0)
[22:19:26.230] Timer tick, asking Buildbucket for the build status
[22:19:26.982] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:26.982] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:60:0) after 1m0s
[22:20:27.438] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:60:0)
[22:20:27.438] Timer tick, asking Buildbucket for the build status
[22:20:27.734] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:27.734] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:61:0) after 1m0s
[22:21:27.898] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:61:0)
[22:21:27.898] Timer tick, asking Buildbucket for the build status
[22:21:30.891] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:30.891] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:62:0) after 1m0s
[22:22:31.189] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:62:0)
[22:22:31.189] Timer tick, asking Buildbucket for the build status
[22:22:31.410] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:31.410] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:63:0) after 1m0s
[22:23:31.809] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:63:0)
[22:23:31.809] Timer tick, asking Buildbucket for the build status
[22:23:32.074] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:32.074] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:64:0) after 1m0s
[22:24:32.174] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:64:0)
[22:24:32.174] Timer tick, asking Buildbucket for the build status
[22:24:33.645] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:33.645] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:65:0) after 1m0s
[22:25:33.765] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:65:0)
[22:25:33.765] Timer tick, asking Buildbucket for the build status
[22:25:33.971] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:33.971] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:66:0) after 1m0s
[22:26:34.794] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:66:0)
[22:26:34.794] Timer tick, asking Buildbucket for the build status
[22:26:35.040] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:35.040] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:67:0) after 1m0s
[22:27:35.317] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:67:0)
[22:27:35.317] Timer tick, asking Buildbucket for the build status
[22:27:36.154] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:36.154] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:68:0) after 1m0s
[22:28:36.180] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:68:0)
[22:28:36.180] Timer tick, asking Buildbucket for the build status
[22:28:36.715] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:36.715] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:69:0) after 1m0s
[22:29:36.789] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:69:0)
[22:29:36.789] Timer tick, asking Buildbucket for the build status
[22:29:36.972] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:36.972] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:70:0) after 1m0s
[22:30:37.147] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:70:0)
[22:30:37.147] Timer tick, asking Buildbucket for the build status
[22:30:37.317] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:37.317] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:71:0) after 1m0s
[22:31:37.519] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:71:0)
[22:31:37.519] Timer tick, asking Buildbucket for the build status
[22:31:37.827] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:37.827] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:72:0) after 1m0s
[22:32:38.253] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:72:0)
[22:32:38.253] Timer tick, asking Buildbucket for the build status
[22:32:38.669] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:38.669] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:73:0) after 1m0s
[22:33:38.855] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:73:0)
[22:33:38.855] Timer tick, asking Buildbucket for the build status
[22:33:39.663] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:39.663] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:74:0) after 1m0s
[22:34:39.689] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:74:0)
[22:34:39.689] Timer tick, asking Buildbucket for the build status
[22:34:40.149] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:40.149] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:75:0) after 1m0s
[22:35:40.195] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:75:0)
[22:35:40.195] Timer tick, asking Buildbucket for the build status
[22:35:40.656] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:40.656] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:76:0) after 1m0s
[22:36:40.730] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:76:0)
[22:36:40.730] Timer tick, asking Buildbucket for the build status
[22:36:50.180] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:50.180] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:77:0) after 1m0s
[22:37:50.255] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:77:0)
[22:37:50.255] Timer tick, asking Buildbucket for the build status
[22:37:51.032] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:51.032] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:78:0) after 1m0s
[22:38:51.099] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:78:0)
[22:38:51.099] Timer tick, asking Buildbucket for the build status
[22:38:51.752] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:51.752] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:79:0) after 1m0s
[22:39:51.777] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:79:0)
[22:39:51.777] Timer tick, asking Buildbucket for the build status
[22:39:52.306] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:52.306] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:80:0) after 1m0s
[22:40:52.390] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:80:0)
[22:40:52.390] Timer tick, asking Buildbucket for the build status
[22:40:52.523] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:52.523] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:81:0) after 1m0s
[22:41:52.698] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:81:0)
[22:41:52.698] Timer tick, asking Buildbucket for the build status
[22:41:53.130] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:53.130] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:82:0) after 1m0s
[22:42:53.269] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:82:0)
[22:42:53.269] Timer tick, asking Buildbucket for the build status
[22:42:53.622] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:53.622] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:83:0) after 1m0s
[22:43:53.785] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:83:0)
[22:43:53.785] Timer tick, asking Buildbucket for the build status
[22:43:54.257] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:54.257] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:84:0) after 1m0s
[22:44:54.274] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:84:0)
[22:44:54.274] Timer tick, asking Buildbucket for the build status
[22:44:54.924] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:54.924] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:85:0) after 1m0s
[22:45:54.994] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:85:0)
[22:45:54.994] Timer tick, asking Buildbucket for the build status
[22:45:55.625] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:55.626] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:86:0) after 1m0s
[22:46:55.651] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:86:0)
[22:46:55.651] Timer tick, asking Buildbucket for the build status
[22:46:56.799] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:56.799] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:87:0) after 1m0s
[22:47:56.933] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:87:0)
[22:47:56.933] Timer tick, asking Buildbucket for the build status
[22:47:57.755] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:57.755] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:88:0) after 1m0s
[22:48:57.783] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:88:0)
[22:48:57.783] Timer tick, asking Buildbucket for the build status
[22:48:57.956] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:57.956] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:89:0) after 1m0s
[22:49:58.051] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:89:0)
[22:49:58.051] Timer tick, asking Buildbucket for the build status
[22:49:58.431] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:58.431] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:90:0) after 1m0s
[22:50:58.419] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:90:0)
[22:50:58.419] Timer tick, asking Buildbucket for the build status
[22:50:59.086] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:59.086] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:91:0) after 1m0s
[22:51:59.121] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:91:0)
[22:51:59.121] Timer tick, asking Buildbucket for the build status
[22:51:59.437] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:59.437] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:92:0) after 1m0s
[22:52:59.475] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:92:0)
[22:52:59.475] Timer tick, asking Buildbucket for the build status
[22:52:59.955] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:59.955] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:93:0) after 1m0s
[22:54:00.007] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:93:0)
[22:54:00.007] Timer tick, asking Buildbucket for the build status
[22:54:00.229] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:00.229] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:94:0) after 1m0s
[22:55:00.390] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:94:0)
[22:55:00.390] Timer tick, asking Buildbucket for the build status
[22:55:00.578] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:00.578] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:95:0) after 1m0s
[22:56:00.630] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:95:0)
[22:56:00.630] Timer tick, asking Buildbucket for the build status
[22:56:00.961] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:00.961] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:96:0) after 1m0s
[22:57:00.955] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:96:0)
[22:57:00.955] Timer tick, asking Buildbucket for the build status
[22:57:01.136] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:01.137] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:97:0) after 1m0s
[22:58:01.174] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:97:0)
[22:58:01.174] Timer tick, asking Buildbucket for the build status
[22:58:02.030] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:02.030] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:98:0) after 1m0s
[22:59:02.078] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:98:0)
[22:59:02.078] Timer tick, asking Buildbucket for the build status
[22:59:02.982] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:02.982] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:99:0) after 1m0s
[23:00:02.999] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:99:0)
[23:00:02.999] Timer tick, asking Buildbucket for the build status
[23:00:03.182] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:03.182] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:100:0) after 1m0s
[23:01:03.280] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:100:0)
[23:01:03.280] Timer tick, asking Buildbucket for the build status
[23:01:03.504] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:03.504] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:101:0) after 1m0s
[23:02:03.554] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:101:0)
[23:02:03.554] Timer tick, asking Buildbucket for the build status
[23:02:04.016] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:04.016] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:102:0) after 1m0s
[23:03:04.178] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:102:0)
[23:03:04.178] Timer tick, asking Buildbucket for the build status
[23:03:04.441] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:04.441] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:103:0) after 1m0s
[23:04:04.532] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:103:0)
[23:04:04.532] Timer tick, asking Buildbucket for the build status
[23:04:04.858] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:04.858] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:104:0) after 1m0s
[23:05:04.895] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:104:0)
[23:05:04.895] Timer tick, asking Buildbucket for the build status
[23:05:05.350] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:05.350] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:105:0) after 1m0s
[23:06:05.407] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:105:0)
[23:06:05.407] Timer tick, asking Buildbucket for the build status
[23:06:05.644] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:05.644] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:106:0) after 1m0s
[23:07:05.681] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:106:0)
[23:07:05.681] Timer tick, asking Buildbucket for the build status
[23:07:06.161] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:06.161] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:107:0) after 1m0s
[23:08:06.196] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:107:0)
[23:08:06.196] Timer tick, asking Buildbucket for the build status
[23:08:06.770] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:06.770] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:108:0) after 1m0s
[23:09:06.837] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:108:0)
[23:09:06.837] Timer tick, asking Buildbucket for the build status
[23:09:07.748] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:07.748] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:109:0) after 1m0s
[23:10:07.834] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:109:0)
[23:10:07.834] Timer tick, asking Buildbucket for the build status
[23:10:08.509] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:08.509] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:110:0) after 1m0s
[23:11:09.198] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:110:0)
[23:11:09.198] Timer tick, asking Buildbucket for the build status
[23:11:09.542] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:09.542] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:111:0) after 1m0s
[23:12:09.645] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:111:0)
[23:12:09.645] Timer tick, asking Buildbucket for the build status
[23:12:10.105] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:10.105] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:112:0) after 1m0s
[23:13:10.390] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:112:0)
[23:13:10.390] Timer tick, asking Buildbucket for the build status
[23:13:10.683] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:10.683] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:113:0) after 1m0s
[23:14:10.690] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:113:0)
[23:14:10.690] Timer tick, asking Buildbucket for the build status
[23:14:11.016] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:11.016] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:114:0) after 1m0s
[23:15:11.191] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:114:0)
[23:15:11.191] Timer tick, asking Buildbucket for the build status
[23:15:12.067] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:12.067] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:115:0) after 1m0s
[23:16:12.749] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:115:0)
[23:16:12.749] Timer tick, asking Buildbucket for the build status
[23:16:13.514] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:13.514] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:116:0) after 1m0s
[23:17:13.568] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:116:0)
[23:17:13.568] Timer tick, asking Buildbucket for the build status
[23:17:13.875] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:13.875] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:117:0) after 1m0s
[23:18:14.107] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:117:0)
[23:18:14.107] Timer tick, asking Buildbucket for the build status
[23:18:14.523] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:14.523] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:118:0) after 1m0s
[23:19:14.771] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:118:0)
[23:19:14.771] Timer tick, asking Buildbucket for the build status
[23:19:15.091] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:15.091] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:119:0) after 1m0s
[23:20:15.362] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:119:0)
[23:20:15.362] Timer tick, asking Buildbucket for the build status
[23:20:15.706] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:15.706] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:120:0) after 1m0s
[23:21:15.835] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:120:0)
[23:21:15.835] Timer tick, asking Buildbucket for the build status
[23:21:16.047] Build 8901943970360666992: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:16.047] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9067374032881616592:121:0) after 1m0s
[23:21:20.913] Received PubSub notification, asking Buildbucket for the build status
[23:21:24.192] Build 8901943970360666992: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[23:21:24.192] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1568848880135924",
"created_by": "project:chromium",
"created_ts": "1568841691587099",
"failure_reason": "BUILD_FAILURE",
"id": "8901943970360666992",
"parameters_json": "{\"builder_name\": \"ToTiOS\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"ad8820a6cfe9b22fa9530ba88681cf3865b36e58\"}}",
"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\": \"build286-m9\", \"branch\": \"refs/heads/master\", \"buildername\": \"ToTiOS\", \"buildnumber\": 9958, \"got_angle_revision\": \"e34564bafe835562087de0a2fb3c8386e0f6b0dd\", \"got_dawn_revision\": \"785ec66b66a3577e4f17306a10ab30b7f92c49ae\", \"got_nacl_revision\": \"a9e27d5c7f1b6edf7371edab79e104578d62c4f5\", \"got_revision\": \"ad8820a6cfe9b22fa9530ba88681cf3865b36e58\", \"got_revision_cp\": \"refs/heads/master@{#697768}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"46e424ecc720115690fc6a2649654aee0cbfe1da\", \"got_v8_revision_cp\": \"refs/heads/7.9.63@{#1}\", \"got_webrtc_revision\": \"86314cfb5dc09bba15a1607585e9ddd544078ac5\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#29212}\", \"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\": \"ad8820a6cfe9b22fa9530ba88681cf3865b36e58\", \"swarm_hashes\": {\"0\": \"529918f61c3cb39402df63f3f2c15d69b620e4dd\", \"1\": \"dd1ffc9ffb3c6fd60bc8d44804a382f8650d1592\", \"10\": \"e175e4a886c6b7bdc4b10c48a71d06a71451e947\", \"11\": \"865e359cfc56057e8f4c355dd4bd4ef1c87a4498\", \"12\": \"79581db7732ceb4da1fbdd6876004e68d7314ede\", \"13\": \"4311608b3a86a871d63776f1d7791c9700d54a95\", \"14\": \"8c8c0b46527614ad5baa7a097ef277da61e8894a\", \"15\": \"906957c8b5f31cf1e5801f64af96b1c32051d688\", \"16\": \"027acc2cf83eb614913954cb549ceae6164a4c7f\", \"2\": \"23aa749decf409082a85a501d281f5588dc11bc4\", \"3\": \"c622fcd3a02684d6b000f5b3eb348da3822996e1\", \"4\": \"7895954288dadcf2535d9c8ba1d41c6b6fae6dfb\", \"5\": \"cc011bc3aacac951296d4acad2fe9e5b65361f14\", \"6\": \"24584b95a24cbd0f3cb20642ed25d36ed004fe73\", \"7\": \"83205c80bd2bf51b2f58754f1e97063a8796de87\", \"8\": \"0a0046291f87b1e1fadf124b5ad3f8e960901c37\", \"9\": \"081848e1f1060c9087be5fc8a3ef3bbdd7822103\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4a4feefdd7e355061708c01720b06a0ca8c49ffc9968a2ebc674541738676b8f_v2\", \"builder_5372538a354f163a20d332d881c6c5d85e35654ac8a57c760866f12033f8a8a5_v2\", \"builder_69caa34b8f39daac28a821cac2619730401d46a6479812cc34796e3bf180418a_v2\", \"builder_ae5debd901c74ee0906916cb62621bceb2a8b657b42c2e2e9e89a0f6a2e5e53b_v2\", \"builder_d42b89eab403033f5a2a8102ec9c436397350cedb439d5e09b11874a5784adb4_v2\", \"git\", \"goma_v2\", \"vpython\", \"xcode_ios_11a419c\", \"xcode_ios_11a420a\", \"xcode_ios_11m392r\"], \"cores\": [\"24\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-E5-2697_v2\"], \"gce\": [\"0\"], \"gpu\": [\"1002\", \"1002:679e\", \"1002:679e-4.0.11-3.2.8\"], \"hidpi\": [\"0\"], \"id\": [\"build286-m9\"], \"mac_model\": [\"MacPro6,1\"], \"machine_type\": [\"n1-standard-16\"], \"os\": [\"Mac\", \"Mac-10.14\", \"Mac-10.14.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.10\"], \"server_version\": [\"4443-3a52fcb\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-atl\", \"us-atl-golo\", \"us-atl-golo-m9\"]}}, \"ui\": {\"info\": \"Failed ios_web_inttests (iPhone 6s iOS 12.2).\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1568841699395257",
"status": "COMPLETED",
"status_changed_ts": "1568841700706384",
"tags": [
"build_address:luci.chromium.ci/ToTiOS/9958",
"builder:ToTiOS",
"buildset:commit/git/ad8820a6cfe9b22fa9530ba88681cf3865b36e58",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ad8820a6cfe9b22fa9530ba88681cf3865b36e58",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9067374032881616592",
"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/8901943970360666992/+/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:475f120c2bc36310",
"user_agent:luci-scheduler"
],
"updated_ts": "1568848880244519",
"url": "https://ci.chromium.org/b/8901943970360666992",
"utcnow_ts": "1568848884181287"
}
}
[23:21:24.192] Invocation finished in 1h59m54.392660525s with status FAILED