[12:47:46.291] New invocation is queued and will start shortly
[12:47:47.467] Starting the invocation (attempt 1)
[12:47:47.508] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:47:47.508] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:47:47.508] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9070214859649707936",
"parameters_json": "{\"builder_name\":\"ToTiOS\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"77948441ad0a6ec4c6e9ffbf52c88bf26bad4383\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:ToTiOS",
"scheduler_invocation_id:9070214859649707936",
"scheduler_job_id:chromium/ToTiOS",
"user_agent:luci-scheduler",
"buildset:commit/git/77948441ad0a6ec4c6e9ffbf52c88bf26bad4383",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/77948441ad0a6ec4c6e9ffbf52c88bf26bad4383",
"gitiles_ref:refs/heads/master"
]
}
[12:47:48.238] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1566132467708671",
"id": "8904784797498530000",
"parameters_json": "{\"builder_name\": \"ToTiOS\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"77948441ad0a6ec4c6e9ffbf52c88bf26bad4383\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1566132468078369",
"tags": [
"build_address:luci.chromium.ci/ToTiOS/9487",
"builder:ToTiOS",
"buildset:commit/git/77948441ad0a6ec4c6e9ffbf52c88bf26bad4383",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/77948441ad0a6ec4c6e9ffbf52c88bf26bad4383",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9070214859649707936",
"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/8904784797498530000/+/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": "1566132468078470",
"url": "https://ci.chromium.org/b/8904784797498530000",
"utcnow_ts": "1566132468224669"
}
}
[12:47:48.238] Task URL: https://ci.chromium.org/b/8904784797498530000
[12:47:48.238] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:2:0) after 1m0s
[12:48:54.151] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:2:0)
[12:48:54.151] Timer tick, asking Buildbucket for the build status
[12:48:55.303] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:55.303] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:3:0) after 1m0s
[12:49:15.473] Received PubSub notification, asking Buildbucket for the build status
[12:49:15.567] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:55.393] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:3:0)
[12:49:55.393] Timer tick, asking Buildbucket for the build status
[12:49:55.717] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:55.717] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:5:0) after 1m0s
[12:50:55.738] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:5:0)
[12:50:55.738] Timer tick, asking Buildbucket for the build status
[12:50:55.899] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:55.900] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:6:0) after 1m0s
[12:51:55.947] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:6:0)
[12:51:55.947] Timer tick, asking Buildbucket for the build status
[12:51:56.154] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:56.154] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:7:0) after 1m0s
[12:52:56.184] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:7:0)
[12:52:56.184] Timer tick, asking Buildbucket for the build status
[12:52:56.324] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:56.324] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:8:0) after 1m0s
[12:53:56.405] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:8:0)
[12:53:56.405] Timer tick, asking Buildbucket for the build status
[12:53:56.850] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:56.850] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:9:0) after 1m0s
[12:54:56.895] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:9:0)
[12:54:56.895] Timer tick, asking Buildbucket for the build status
[12:54:57.038] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:57.038] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:10:0) after 1m0s
[12:55:57.083] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:10:0)
[12:55:57.083] Timer tick, asking Buildbucket for the build status
[12:55:57.282] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:57.282] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:11:0) after 1m0s
[12:56:57.354] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:11:0)
[12:56:57.354] Timer tick, asking Buildbucket for the build status
[12:56:57.840] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:57.840] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:12:0) after 1m0s
[12:57:57.908] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:12:0)
[12:57:57.909] Timer tick, asking Buildbucket for the build status
[12:57:58.193] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:58.193] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:13:0) after 1m0s
[12:58:58.224] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:13:0)
[12:58:58.224] Timer tick, asking Buildbucket for the build status
[12:58:58.377] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:58.377] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:14:0) after 1m0s
[12:59:58.497] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:14:0)
[12:59:58.497] Timer tick, asking Buildbucket for the build status
[12:59:58.671] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:58.671] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:15:0) after 1m0s
[13:00:58.706] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:15:0)
[13:00:58.706] Timer tick, asking Buildbucket for the build status
[13:00:59.125] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:59.125] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:16:0) after 1m0s
[13:01:59.148] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:16:0)
[13:01:59.148] Timer tick, asking Buildbucket for the build status
[13:01:59.399] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:59.399] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:17:0) after 1m0s
[13:02:59.422] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:17:0)
[13:02:59.422] Timer tick, asking Buildbucket for the build status
[13:02:59.565] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:59.565] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:18:0) after 1m0s
[13:03:59.586] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:18:0)
[13:03:59.586] Timer tick, asking Buildbucket for the build status
[13:03:59.777] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:59.777] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:19:0) after 1m0s
[13:04:59.852] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:19:0)
[13:04:59.852] Timer tick, asking Buildbucket for the build status
[13:05:00.053] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:00.053] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:20:0) after 1m0s
[13:06:00.142] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:20:0)
[13:06:00.142] Timer tick, asking Buildbucket for the build status
[13:06:00.569] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:00.569] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:21:0) after 1m0s
[13:07:00.599] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:21:0)
[13:07:00.599] Timer tick, asking Buildbucket for the build status
[13:07:01.229] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:01.229] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:22:0) after 1m0s
[13:08:01.496] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:22:0)
[13:08:01.496] Timer tick, asking Buildbucket for the build status
[13:08:01.833] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:01.833] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:23:0) after 1m0s
[13:09:01.936] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:23:0)
[13:09:01.936] Timer tick, asking Buildbucket for the build status
[13:09:02.649] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:02.649] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:24:0) after 1m0s
[13:10:02.993] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:24:0)
[13:10:02.993] Timer tick, asking Buildbucket for the build status
[13:10:03.462] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:03.462] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:25:0) after 1m0s
[13:11:03.499] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:25:0)
[13:11:03.499] Timer tick, asking Buildbucket for the build status
[13:11:03.703] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:03.703] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:26:0) after 1m0s
[13:12:03.895] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:26:0)
[13:12:03.895] Timer tick, asking Buildbucket for the build status
[13:12:04.210] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:04.210] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:27:0) after 1m0s
[13:13:04.323] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:27:0)
[13:13:04.323] Timer tick, asking Buildbucket for the build status
[13:13:04.820] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:04.820] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:28:0) after 1m0s
[13:14:04.844] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:28:0)
[13:14:04.844] Timer tick, asking Buildbucket for the build status
[13:14:04.999] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:04.999] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:29:0) after 1m0s
[13:15:05.210] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:29:0)
[13:15:05.210] Timer tick, asking Buildbucket for the build status
[13:15:05.533] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:05.533] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:30:0) after 1m0s
[13:16:05.631] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:30:0)
[13:16:05.631] Timer tick, asking Buildbucket for the build status
[13:16:06.809] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:06.809] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:31:0) after 1m0s
[13:17:07.086] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:31:0)
[13:17:07.086] Timer tick, asking Buildbucket for the build status
[13:17:07.243] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:07.243] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:32:0) after 1m0s
[13:18:07.602] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:32:0)
[13:18:07.602] Timer tick, asking Buildbucket for the build status
[13:18:07.755] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:07.755] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:33:0) after 1m0s
[13:19:07.963] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:33:0)
[13:19:07.963] Timer tick, asking Buildbucket for the build status
[13:19:08.511] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:08.511] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:34:0) after 1m0s
[13:20:08.694] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:34:0)
[13:20:08.694] Timer tick, asking Buildbucket for the build status
[13:20:12.627] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:12.627] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:35:0) after 1m0s
[13:21:12.814] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:35:0)
[13:21:12.814] Timer tick, asking Buildbucket for the build status
[13:21:13.065] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:13.065] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:36:0) after 1m0s
[13:22:13.091] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:36:0)
[13:22:13.091] Timer tick, asking Buildbucket for the build status
[13:22:13.611] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:13.611] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:37:0) after 1m0s
[13:23:13.683] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:37:0)
[13:23:13.683] Timer tick, asking Buildbucket for the build status
[13:23:13.848] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:13.848] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:38:0) after 1m0s
[13:24:13.914] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:38:0)
[13:24:13.914] Timer tick, asking Buildbucket for the build status
[13:24:14.066] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:14.066] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:39:0) after 1m0s
[13:25:14.092] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:39:0)
[13:25:14.092] Timer tick, asking Buildbucket for the build status
[13:25:14.305] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:14.305] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:40:0) after 1m0s
[13:26:14.452] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:40:0)
[13:26:14.452] Timer tick, asking Buildbucket for the build status
[13:26:14.623] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:14.623] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:41:0) after 1m0s
[13:27:14.702] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:41:0)
[13:27:14.702] Timer tick, asking Buildbucket for the build status
[13:27:14.869] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:14.869] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:42:0) after 1m0s
[13:28:14.903] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:42:0)
[13:28:14.903] Timer tick, asking Buildbucket for the build status
[13:28:15.223] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:15.223] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:43:0) after 1m0s
[13:29:15.338] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:43:0)
[13:29:15.338] Timer tick, asking Buildbucket for the build status
[13:29:15.575] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:15.575] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:44:0) after 1m0s
[13:30:15.675] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:44:0)
[13:30:15.675] Timer tick, asking Buildbucket for the build status
[13:30:15.929] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:15.929] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:45:0) after 1m0s
[13:31:16.019] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:45:0)
[13:31:16.019] Timer tick, asking Buildbucket for the build status
[13:31:16.220] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:16.220] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:46:0) after 1m0s
[13:32:16.286] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:46:0)
[13:32:16.286] Timer tick, asking Buildbucket for the build status
[13:32:16.964] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:16.964] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:47:0) after 1m0s
[13:33:17.197] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:47:0)
[13:33:17.197] Timer tick, asking Buildbucket for the build status
[13:33:18.047] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:18.047] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:48:0) after 1m0s
[13:34:18.091] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:48:0)
[13:34:18.092] Timer tick, asking Buildbucket for the build status
[13:34:18.367] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:18.367] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:49:0) after 1m0s
[13:35:18.393] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:49:0)
[13:35:18.393] Timer tick, asking Buildbucket for the build status
[13:35:18.724] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:18.724] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:50:0) after 1m0s
[13:36:18.891] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:50:0)
[13:36:18.891] Timer tick, asking Buildbucket for the build status
[13:36:20.053] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:20.053] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:51:0) after 1m0s
[13:37:20.191] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:51:0)
[13:37:20.191] Timer tick, asking Buildbucket for the build status
[13:37:20.583] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:20.583] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:52:0) after 1m0s
[13:38:20.608] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:52:0)
[13:38:20.608] Timer tick, asking Buildbucket for the build status
[13:38:20.878] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:20.878] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:53:0) after 1m0s
[13:39:20.907] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:53:0)
[13:39:20.907] Timer tick, asking Buildbucket for the build status
[13:39:21.127] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:21.127] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:54:0) after 1m0s
[13:40:21.164] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:54:0)
[13:40:21.164] Timer tick, asking Buildbucket for the build status
[13:40:21.327] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:21.327] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:55:0) after 1m0s
[13:41:21.351] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:55:0)
[13:41:21.351] Timer tick, asking Buildbucket for the build status
[13:41:21.563] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:21.563] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:56:0) after 1m0s
[13:42:21.681] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:56:0)
[13:42:21.681] Timer tick, asking Buildbucket for the build status
[13:42:22.268] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:22.268] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:57:0) after 1m0s
[13:43:22.306] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:57:0)
[13:43:22.306] Timer tick, asking Buildbucket for the build status
[13:43:22.503] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:22.503] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:58:0) after 1m0s
[13:44:22.536] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:58:0)
[13:44:22.536] Timer tick, asking Buildbucket for the build status
[13:44:22.765] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:22.766] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:59:0) after 1m0s
[13:45:22.790] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:59:0)
[13:45:22.790] Timer tick, asking Buildbucket for the build status
[13:45:22.958] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:22.958] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:60:0) after 1m0s
[13:46:22.989] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:60:0)
[13:46:22.989] Timer tick, asking Buildbucket for the build status
[13:46:23.283] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:23.283] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:61:0) after 1m0s
[13:47:23.307] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:61:0)
[13:47:23.307] Timer tick, asking Buildbucket for the build status
[13:47:23.528] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:23.528] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:62:0) after 1m0s
[13:48:23.604] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:62:0)
[13:48:23.604] Timer tick, asking Buildbucket for the build status
[13:48:23.775] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:23.775] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:63:0) after 1m0s
[13:49:23.800] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:63:0)
[13:49:23.800] Timer tick, asking Buildbucket for the build status
[13:49:23.983] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:23.983] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:64:0) after 1m0s
[13:50:24.004] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:64:0)
[13:50:24.004] Timer tick, asking Buildbucket for the build status
[13:50:24.159] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:24.159] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:65:0) after 1m0s
[13:51:24.201] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:65:0)
[13:51:24.201] Timer tick, asking Buildbucket for the build status
[13:51:24.356] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:24.356] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:66:0) after 1m0s
[13:52:24.845] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:66:0)
[13:52:24.845] Timer tick, asking Buildbucket for the build status
[13:52:25.021] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:25.021] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:67:0) after 1m0s
[13:53:25.217] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:67:0)
[13:53:25.217] Timer tick, asking Buildbucket for the build status
[13:53:25.987] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:25.987] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:68:0) after 1m0s
[13:54:26.019] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:68:0)
[13:54:26.019] Timer tick, asking Buildbucket for the build status
[13:54:26.438] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:26.438] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:69:0) after 1m0s
[13:55:26.500] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:69:0)
[13:55:26.500] Timer tick, asking Buildbucket for the build status
[13:55:26.704] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:26.704] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:70:0) after 1m0s
[13:56:26.999] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:70:0)
[13:56:26.999] Timer tick, asking Buildbucket for the build status
[13:56:27.481] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:27.481] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:71:0) after 1m0s
[13:57:27.500] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:71:0)
[13:57:27.500] Timer tick, asking Buildbucket for the build status
[13:57:27.673] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:27.673] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:72:0) after 1m0s
[13:58:27.696] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:72:0)
[13:58:27.696] Timer tick, asking Buildbucket for the build status
[13:58:27.986] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:27.986] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:73:0) after 1m0s
[13:59:28.152] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:73:0)
[13:59:28.152] Timer tick, asking Buildbucket for the build status
[13:59:28.343] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:28.343] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:74:0) after 1m0s
[14:00:28.514] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:74:0)
[14:00:28.514] Timer tick, asking Buildbucket for the build status
[14:00:28.665] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:28.665] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:75:0) after 1m0s
[14:01:28.887] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:75:0)
[14:01:28.887] Timer tick, asking Buildbucket for the build status
[14:01:29.556] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:29.556] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:76:0) after 1m0s
[14:02:29.578] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:76:0)
[14:02:29.578] Timer tick, asking Buildbucket for the build status
[14:02:30.394] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:30.394] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:77:0) after 1m0s
[14:03:30.430] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:77:0)
[14:03:30.430] Timer tick, asking Buildbucket for the build status
[14:03:30.711] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:30.711] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:78:0) after 1m0s
[14:04:30.849] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:78:0)
[14:04:30.849] Timer tick, asking Buildbucket for the build status
[14:04:31.784] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:31.784] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:79:0) after 1m0s
[14:05:32.020] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:79:0)
[14:05:32.020] Timer tick, asking Buildbucket for the build status
[14:05:32.725] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:32.725] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:80:0) after 1m0s
[14:06:32.812] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:80:0)
[14:06:32.812] Timer tick, asking Buildbucket for the build status
[14:06:33.564] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:33.564] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:81:0) after 1m0s
[14:07:33.726] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:81:0)
[14:07:33.726] Timer tick, asking Buildbucket for the build status
[14:07:34.153] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:34.153] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:82:0) after 1m0s
[14:08:34.301] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:82:0)
[14:08:34.301] Timer tick, asking Buildbucket for the build status
[14:08:34.922] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:34.922] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:83:0) after 1m0s
[14:09:41.532] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:83:0)
[14:09:41.532] Timer tick, asking Buildbucket for the build status
[14:09:41.683] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:41.683] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:84:0) after 1m0s
[14:10:41.710] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:84:0)
[14:10:41.710] Timer tick, asking Buildbucket for the build status
[14:10:42.102] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:42.102] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:85:0) after 1m0s
[14:11:42.130] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:85:0)
[14:11:42.130] Timer tick, asking Buildbucket for the build status
[14:11:43.212] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:43.212] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:86:0) after 1m0s
[14:12:43.237] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:86:0)
[14:12:43.237] Timer tick, asking Buildbucket for the build status
[14:12:43.763] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:43.763] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:87:0) after 1m0s
[14:13:43.794] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:87:0)
[14:13:43.794] Timer tick, asking Buildbucket for the build status
[14:13:44.400] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:44.400] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:88:0) after 1m0s
[14:14:44.444] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:88:0)
[14:14:44.444] Timer tick, asking Buildbucket for the build status
[14:14:44.644] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:44.644] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:89:0) after 1m0s
[14:15:44.669] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:89:0)
[14:15:44.669] Timer tick, asking Buildbucket for the build status
[14:15:44.828] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:44.828] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:90:0) after 1m0s
[14:16:44.907] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:90:0)
[14:16:44.907] Timer tick, asking Buildbucket for the build status
[14:16:45.067] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:45.067] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:91:0) after 1m0s
[14:17:45.066] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:91:0)
[14:17:45.066] Timer tick, asking Buildbucket for the build status
[14:17:45.222] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:45.222] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:92:0) after 1m0s
[14:18:45.309] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:92:0)
[14:18:45.310] Timer tick, asking Buildbucket for the build status
[14:18:45.507] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:45.507] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:93:0) after 1m0s
[14:19:45.596] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:93:0)
[14:19:45.596] Timer tick, asking Buildbucket for the build status
[14:19:46.315] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:46.315] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:94:0) after 1m0s
[14:20:46.387] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:94:0)
[14:20:46.387] Timer tick, asking Buildbucket for the build status
[14:20:48.722] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:48.722] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:95:0) after 1m0s
[14:21:48.837] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:95:0)
[14:21:48.837] Timer tick, asking Buildbucket for the build status
[14:21:49.979] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:49.979] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:96:0) after 1m0s
[14:22:50.001] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:96:0)
[14:22:50.001] Timer tick, asking Buildbucket for the build status
[14:22:50.200] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:50.200] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:97:0) after 1m0s
[14:23:50.276] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:97:0)
[14:23:50.276] Timer tick, asking Buildbucket for the build status
[14:23:50.418] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:50.418] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:98:0) after 1m0s
[14:24:50.497] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:98:0)
[14:24:50.497] Timer tick, asking Buildbucket for the build status
[14:24:51.207] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:51.207] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:99:0) after 1m0s
[14:25:51.247] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:99:0)
[14:25:51.247] Timer tick, asking Buildbucket for the build status
[14:25:51.452] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:51.453] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:100:0) after 1m0s
[14:26:51.455] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:100:0)
[14:26:51.455] Timer tick, asking Buildbucket for the build status
[14:26:51.670] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:51.670] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:101:0) after 1m0s
[14:27:51.693] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:101:0)
[14:27:51.693] Timer tick, asking Buildbucket for the build status
[14:27:51.903] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:51.903] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:102:0) after 1m0s
[14:28:52.034] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:102:0)
[14:28:52.034] Timer tick, asking Buildbucket for the build status
[14:28:52.225] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:52.225] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:103:0) after 1m0s
[14:29:52.295] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:103:0)
[14:29:52.295] Timer tick, asking Buildbucket for the build status
[14:29:52.463] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:52.463] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:104:0) after 1m0s
[14:30:52.556] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:104:0)
[14:30:52.556] Timer tick, asking Buildbucket for the build status
[14:30:52.792] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:52.792] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:105:0) after 1m0s
[14:31:52.810] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:105:0)
[14:31:52.810] Timer tick, asking Buildbucket for the build status
[14:31:53.012] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:53.012] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:106:0) after 1m0s
[14:32:53.048] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:106:0)
[14:32:53.048] Timer tick, asking Buildbucket for the build status
[14:32:54.222] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:54.222] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:107:0) after 1m0s
[14:33:54.320] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:107:0)
[14:33:54.320] Timer tick, asking Buildbucket for the build status
[14:33:54.598] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:54.598] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:108:0) after 1m0s
[14:34:54.630] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:108:0)
[14:34:54.630] Timer tick, asking Buildbucket for the build status
[14:34:54.943] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:54.943] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:109:0) after 1m0s
[14:35:55.182] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:109:0)
[14:35:55.182] Timer tick, asking Buildbucket for the build status
[14:35:55.335] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:55.335] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:110:0) after 1m0s
[14:36:55.360] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:110:0)
[14:36:55.360] Timer tick, asking Buildbucket for the build status
[14:36:55.748] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:55.748] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:111:0) after 1m0s
[14:37:55.845] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:111:0)
[14:37:55.845] Timer tick, asking Buildbucket for the build status
[14:37:56.010] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:56.010] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:112:0) after 1m0s
[14:38:56.156] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:112:0)
[14:38:56.156] Timer tick, asking Buildbucket for the build status
[14:38:56.589] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:56.589] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:113:0) after 1m0s
[14:39:56.565] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:113:0)
[14:39:56.566] Timer tick, asking Buildbucket for the build status
[14:39:57.140] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:57.140] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:114:0) after 1m0s
[14:40:57.168] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:114:0)
[14:40:57.168] Timer tick, asking Buildbucket for the build status
[14:40:57.349] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:57.349] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:115:0) after 1m0s
[14:41:57.373] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:115:0)
[14:41:57.373] Timer tick, asking Buildbucket for the build status
[14:41:57.522] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:57.522] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:116:0) after 1m0s
[14:42:57.545] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:116:0)
[14:42:57.545] Timer tick, asking Buildbucket for the build status
[14:42:57.716] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:57.716] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:117:0) after 1m0s
[14:43:57.745] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:117:0)
[14:43:57.745] Timer tick, asking Buildbucket for the build status
[14:43:58.454] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:58.454] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:118:0) after 1m0s
[14:44:58.495] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:118:0)
[14:44:58.495] Timer tick, asking Buildbucket for the build status
[14:44:58.787] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:58.787] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:119:0) after 1m0s
[14:45:58.865] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:119:0)
[14:45:58.865] Timer tick, asking Buildbucket for the build status
[14:45:59.249] Build 8904784797498530000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:59.249] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9070214859649707936:120:0) after 1m0s
[14:46:24.558] Received PubSub notification, asking Buildbucket for the build status
[14:46:24.690] Build 8904784797498530000: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[14:46:24.690] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1566139583498494",
"created_by": "project:chromium",
"created_ts": "1566132467708671",
"failure_reason": "BUILD_FAILURE",
"id": "8904784797498530000",
"parameters_json": "{\"builder_name\": \"ToTiOS\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"77948441ad0a6ec4c6e9ffbf52c88bf26bad4383\"}}",
"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\": 9487, \"got_angle_revision\": \"265fdf0c5fd6b1e28a54c18ca40a8461c7f449a7\", \"got_dawn_revision\": \"ee3de1e1f3da2386197a2c586304deb263890a35\", \"got_nacl_revision\": \"058a26f57015051953a727add26e7e26348b9558\", \"got_revision\": \"77948441ad0a6ec4c6e9ffbf52c88bf26bad4383\", \"got_revision_cp\": \"refs/heads/master@{#687965}\", \"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\": \"77948441ad0a6ec4c6e9ffbf52c88bf26bad4383\", \"swarm_hashes\": {\"0\": \"fe225bad320a1424c9a42cb07157ffb9638e6f81\", \"1\": \"dc72678b80d1aba4fd6b674f7928f225604aee8b\", \"10\": \"b0a935b9053d43a50acf54a05024438d5334111b\", \"11\": \"4f3a2d16b1da8caee5b67c410042e40020fee387\", \"12\": \"ff77bdb2a7d1087bdb49b0835f831cee8af36218\", \"13\": \"d8018f8029b50667f60bacf6f103b20dd886b220\", \"14\": \"c36e737f8fcc672da5ec83192f0ce5bab8d705b3\", \"15\": \"ffd0322137c5aed232b33ab8e26331579f6c5ed3\", \"16\": \"d491b084cb83f1f639e878f9863fa72abb69cc70\", \"2\": \"0ada75245f0563d6cc021bf16f4944b021a2494e\", \"3\": \"4dc4130f88d6d3d64cead7eff1bdcc60c1e1809e\", \"4\": \"8e1113701a10182dce4068ed037832e27e8c2920\", \"5\": \"a0189d6b0093e30e30b08e4e19c7cfe16d397813\", \"6\": \"5611c97a71cfb25be462b8ae9439ebd635675425\", \"7\": \"286246b1fa22fad83698364d2d56d98aa4c8a830\", \"8\": \"af6ee3f3ea31233e044f37f5f5b8ea9110922381\", \"9\": \"518f520bce41babd6c5cee381a83655043aa0a51\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4a4feefdd7e355061708c01720b06a0ca8c49ffc9968a2ebc674541738676b8f_v2\", \"builder_ae5debd901c74ee0906916cb62621bceb2a8b657b42c2e2e9e89a0f6a2e5e53b_v2\", \"builder_d42b89eab403033f5a2a8102ec9c436397350cedb439d5e09b11874a5784adb4_v2\", \"git\", \"goma_v2\", \"vpython\", \"xcode_ios_11m382q\"], \"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\": [\"4397-8786906\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-atl\", \"us-atl-golo\", \"us-atl-golo-m9\"]}}, \"ui\": {\"info\": \"Failed ios_web_inttests (iPhone 6s iOS 12.1).\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1566132530629049",
"status": "COMPLETED",
"status_changed_ts": "1566132533005056",
"tags": [
"build_address:luci.chromium.ci/ToTiOS/9487",
"builder:ToTiOS",
"buildset:commit/git/77948441ad0a6ec4c6e9ffbf52c88bf26bad4383",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/77948441ad0a6ec4c6e9ffbf52c88bf26bad4383",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9070214859649707936",
"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/8904784797498530000/+/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:46bd9682b0797510",
"user_agent:luci-scheduler"
],
"updated_ts": "1566139584039644",
"url": "https://ci.chromium.org/b/8904784797498530000",
"utcnow_ts": "1566139584680559"
}
}
[14:46:24.690] Invocation finished in 1h58m38.416396692s with status FAILED