[09:56:00.151] New invocation is queued and will start shortly
[09:56:01.240] Starting the invocation (attempt 1)
[09:56:01.284] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:56:01.284] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:56:01.284] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9069953875523358672",
"parameters_json": "{\"builder_name\":\"ToTiOS\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"ae6e7c3488ae126827e7da170d7cf84a0b142aca\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:ToTiOS",
"scheduler_invocation_id:9069953875523358672",
"scheduler_job_id:chromium/ToTiOS",
"user_agent:luci-scheduler",
"buildset:commit/git/ae6e7c3488ae126827e7da170d7cf84a0b142aca",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ae6e7c3488ae126827e7da170d7cf84a0b142aca",
"gitiles_ref:refs/heads/master"
]
}
[09:56:02.152] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1566381361444914",
"id": "8904523813500747488",
"parameters_json": "{\"builder_name\": \"ToTiOS\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"ae6e7c3488ae126827e7da170d7cf84a0b142aca\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1566381361992142",
"tags": [
"build_address:luci.chromium.ci/ToTiOS/9550",
"builder:ToTiOS",
"buildset:commit/git/ae6e7c3488ae126827e7da170d7cf84a0b142aca",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ae6e7c3488ae126827e7da170d7cf84a0b142aca",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9069953875523358672",
"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/8904523813500747488/+/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": "1566381361992275",
"url": "https://ci.chromium.org/b/8904523813500747488",
"utcnow_ts": "1566381362142516"
}
}
[09:56:02.152] Task URL: https://ci.chromium.org/b/8904523813500747488
[09:56:02.152] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:2:0) after 1m0s
[09:57:02.207] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:2:0)
[09:57:02.207] Timer tick, asking Buildbucket for the build status
[09:57:02.462] Build 8904523813500747488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:57:02.462] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:3:0) after 1m0s
[09:58:02.658] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:3:0)
[09:58:02.658] Timer tick, asking Buildbucket for the build status
[09:58:03.411] Build 8904523813500747488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:58:03.411] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:4:0) after 1m0s
[09:59:03.590] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:4:0)
[09:59:03.591] Timer tick, asking Buildbucket for the build status
[09:59:07.354] Build 8904523813500747488: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:59:07.354] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:5:0) after 1m0s
[09:59:10.674] Received PubSub notification, asking Buildbucket for the build status
[09:59:11.382] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:07.444] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:5:0)
[10:00:07.444] Timer tick, asking Buildbucket for the build status
[10:00:07.879] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:07.879] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:7:0) after 1m0s
[10:01:08.139] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:7:0)
[10:01:08.139] Timer tick, asking Buildbucket for the build status
[10:01:09.286] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:09.286] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:8:0) after 1m0s
[10:02:09.324] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:8:0)
[10:02:09.324] Timer tick, asking Buildbucket for the build status
[10:02:09.512] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:09.512] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:9:0) after 1m0s
[10:03:09.534] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:9:0)
[10:03:09.534] Timer tick, asking Buildbucket for the build status
[10:03:09.960] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:09.960] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:10:0) after 1m0s
[10:04:09.979] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:10:0)
[10:04:09.979] Timer tick, asking Buildbucket for the build status
[10:04:10.205] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:10.205] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:11:0) after 1m0s
[10:05:10.310] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:11:0)
[10:05:10.310] Timer tick, asking Buildbucket for the build status
[10:05:10.510] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:10.510] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:12:0) after 1m0s
[10:06:10.534] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:12:0)
[10:06:10.534] Timer tick, asking Buildbucket for the build status
[10:06:10.687] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:10.687] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:13:0) after 1m0s
[10:07:10.873] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:13:0)
[10:07:10.873] Timer tick, asking Buildbucket for the build status
[10:07:11.276] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:11.276] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:14:0) after 1m0s
[10:08:11.335] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:14:0)
[10:08:11.335] Timer tick, asking Buildbucket for the build status
[10:08:11.731] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:11.731] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:15:0) after 1m0s
[10:09:11.758] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:15:0)
[10:09:11.758] Timer tick, asking Buildbucket for the build status
[10:09:12.001] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:12.001] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:16:0) after 1m0s
[10:10:12.076] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:16:0)
[10:10:12.076] Timer tick, asking Buildbucket for the build status
[10:10:13.075] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:13.075] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:17:0) after 1m0s
[10:11:13.071] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:17:0)
[10:11:13.071] Timer tick, asking Buildbucket for the build status
[10:11:13.516] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:13.516] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:18:0) after 1m0s
[10:12:13.617] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:18:0)
[10:12:13.617] Timer tick, asking Buildbucket for the build status
[10:12:14.058] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:14.058] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:19:0) after 1m0s
[10:13:14.182] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:19:0)
[10:13:14.182] Timer tick, asking Buildbucket for the build status
[10:13:14.701] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:14.701] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:20:0) after 1m0s
[10:14:14.692] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:20:0)
[10:14:14.692] Timer tick, asking Buildbucket for the build status
[10:14:14.924] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:14.924] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:21:0) after 1m0s
[10:15:14.912] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:21:0)
[10:15:14.912] Timer tick, asking Buildbucket for the build status
[10:15:15.543] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:15.543] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:22:0) after 1m0s
[10:16:15.559] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:22:0)
[10:16:15.559] Timer tick, asking Buildbucket for the build status
[10:16:15.770] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:15.770] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:23:0) after 1m0s
[10:17:15.826] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:23:0)
[10:17:15.826] Timer tick, asking Buildbucket for the build status
[10:17:16.438] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:16.438] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:24:0) after 1m0s
[10:18:16.519] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:24:0)
[10:18:16.520] Timer tick, asking Buildbucket for the build status
[10:18:17.065] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:17.065] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:25:0) after 1m0s
[10:19:17.147] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:25:0)
[10:19:17.147] Timer tick, asking Buildbucket for the build status
[10:19:19.561] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:19.561] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:26:0) after 1m0s
[10:20:19.622] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:26:0)
[10:20:19.622] Timer tick, asking Buildbucket for the build status
[10:20:21.708] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:21.708] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:27:0) after 1m0s
[10:21:21.792] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:27:0)
[10:21:21.792] Timer tick, asking Buildbucket for the build status
[10:21:22.139] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:22.139] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:28:0) after 1m0s
[10:22:22.173] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:28:0)
[10:22:22.173] Timer tick, asking Buildbucket for the build status
[10:22:23.287] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:23.287] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:29:0) after 1m0s
[10:23:23.422] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:29:0)
[10:23:23.422] Timer tick, asking Buildbucket for the build status
[10:23:23.840] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:23.840] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:30:0) after 1m0s
[10:24:23.838] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:30:0)
[10:24:23.838] Timer tick, asking Buildbucket for the build status
[10:24:24.232] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:24.232] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:31:0) after 1m0s
[10:25:24.344] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:31:0)
[10:25:24.344] Timer tick, asking Buildbucket for the build status
[10:25:26.375] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:26.375] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:32:0) after 1m0s
[10:26:26.367] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:32:0)
[10:26:26.367] Timer tick, asking Buildbucket for the build status
[10:26:26.539] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:26.539] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:33:0) after 1m0s
[10:27:26.639] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:33:0)
[10:27:26.639] Timer tick, asking Buildbucket for the build status
[10:27:26.795] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:26.795] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:34:0) after 1m0s
[10:28:26.784] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:34:0)
[10:28:26.784] Timer tick, asking Buildbucket for the build status
[10:28:26.990] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:26.990] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:35:0) after 1m0s
[10:29:27.138] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:35:0)
[10:29:27.138] Timer tick, asking Buildbucket for the build status
[10:29:27.376] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:27.376] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:36:0) after 1m0s
[10:30:27.465] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:36:0)
[10:30:27.465] Timer tick, asking Buildbucket for the build status
[10:30:27.607] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:27.607] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:37:0) after 1m0s
[10:31:27.640] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:37:0)
[10:31:27.640] Timer tick, asking Buildbucket for the build status
[10:31:27.969] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:27.969] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:38:0) after 1m0s
[10:32:28.000] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:38:0)
[10:32:28.000] Timer tick, asking Buildbucket for the build status
[10:32:28.592] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:28.594] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:39:0) after 1m0s
[10:33:28.612] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:39:0)
[10:33:28.612] Timer tick, asking Buildbucket for the build status
[10:33:28.776] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:28.776] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:40:0) after 1m0s
[10:34:28.891] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:40:0)
[10:34:28.891] Timer tick, asking Buildbucket for the build status
[10:34:29.185] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:29.185] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:41:0) after 1m0s
[10:35:29.277] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:41:0)
[10:35:29.277] Timer tick, asking Buildbucket for the build status
[10:35:29.726] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:29.726] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:42:0) after 1m0s
[10:36:29.789] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:42:0)
[10:36:29.789] Timer tick, asking Buildbucket for the build status
[10:36:29.974] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:29.974] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:43:0) after 1m0s
[10:37:30.014] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:43:0)
[10:37:30.014] Timer tick, asking Buildbucket for the build status
[10:37:30.713] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:30.713] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:44:0) after 1m0s
[10:38:30.834] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:44:0)
[10:38:30.834] Timer tick, asking Buildbucket for the build status
[10:38:31.388] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:31.388] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:45:0) after 1m0s
[10:39:31.514] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:45:0)
[10:39:31.514] Timer tick, asking Buildbucket for the build status
[10:39:32.251] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:32.251] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:46:0) after 1m0s
[10:40:32.417] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:46:0)
[10:40:32.417] Timer tick, asking Buildbucket for the build status
[10:40:32.593] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:32.593] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:47:0) after 1m0s
[10:41:32.666] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:47:0)
[10:41:32.666] Timer tick, asking Buildbucket for the build status
[10:41:32.887] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:32.887] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:48:0) after 1m0s
[10:42:32.917] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:48:0)
[10:42:32.917] Timer tick, asking Buildbucket for the build status
[10:42:33.082] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:33.082] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:49:0) after 1m0s
[10:43:33.165] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:49:0)
[10:43:33.165] Timer tick, asking Buildbucket for the build status
[10:43:34.755] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:34.755] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:50:0) after 1m0s
[10:44:34.826] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:50:0)
[10:44:34.826] Timer tick, asking Buildbucket for the build status
[10:44:35.199] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:35.199] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:51:0) after 1m0s
[10:45:35.259] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:51:0)
[10:45:35.259] Timer tick, asking Buildbucket for the build status
[10:45:35.555] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:35.555] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:52:0) after 1m0s
[10:46:35.614] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:52:0)
[10:46:35.614] Timer tick, asking Buildbucket for the build status
[10:46:36.850] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:36.850] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:53:0) after 1m0s
[10:47:36.925] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:53:0)
[10:47:36.925] Timer tick, asking Buildbucket for the build status
[10:47:37.215] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:37.215] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:54:0) after 1m0s
[10:48:37.244] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:54:0)
[10:48:37.244] Timer tick, asking Buildbucket for the build status
[10:48:37.524] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:37.524] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:55:0) after 1m0s
[10:49:37.564] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:55:0)
[10:49:37.564] Timer tick, asking Buildbucket for the build status
[10:49:37.745] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:37.745] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:56:0) after 1m0s
[10:50:37.828] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:56:0)
[10:50:37.828] Timer tick, asking Buildbucket for the build status
[10:50:38.147] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:38.147] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:57:0) after 1m0s
[10:51:38.291] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:57:0)
[10:51:38.291] Timer tick, asking Buildbucket for the build status
[10:51:38.697] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:38.697] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:58:0) after 1m0s
[10:52:38.718] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:58:0)
[10:52:38.718] Timer tick, asking Buildbucket for the build status
[10:52:38.896] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:38.896] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:59:0) after 1m0s
[10:53:38.963] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:59:0)
[10:53:38.963] Timer tick, asking Buildbucket for the build status
[10:53:40.568] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:40.568] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:60:0) after 1m0s
[10:54:40.627] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:60:0)
[10:54:40.627] Timer tick, asking Buildbucket for the build status
[10:54:41.072] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:41.072] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:61:0) after 1m0s
[10:55:41.071] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:61:0)
[10:55:41.071] Timer tick, asking Buildbucket for the build status
[10:55:41.217] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:41.217] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:62:0) after 1m0s
[10:56:41.271] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:62:0)
[10:56:41.271] Timer tick, asking Buildbucket for the build status
[10:56:41.672] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:41.672] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:63:0) after 1m0s
[10:57:41.755] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:63:0)
[10:57:41.755] Timer tick, asking Buildbucket for the build status
[10:57:42.073] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:42.073] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:64:0) after 1m0s
[10:58:42.101] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:64:0)
[10:58:42.101] Timer tick, asking Buildbucket for the build status
[10:58:42.312] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:42.312] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:65:0) after 1m0s
[10:59:42.336] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:65:0)
[10:59:42.336] Timer tick, asking Buildbucket for the build status
[10:59:42.572] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:42.572] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:66:0) after 1m0s
[11:00:42.636] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:66:0)
[11:00:42.636] Timer tick, asking Buildbucket for the build status
[11:00:42.821] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:42.821] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:67:0) after 1m0s
[11:01:42.895] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:67:0)
[11:01:42.895] Timer tick, asking Buildbucket for the build status
[11:01:44.518] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:44.518] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:68:0) after 1m0s
[11:02:44.592] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:68:0)
[11:02:44.592] Timer tick, asking Buildbucket for the build status
[11:02:46.217] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:46.217] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:69:0) after 1m0s
[11:03:46.372] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:69:0)
[11:03:46.372] Timer tick, asking Buildbucket for the build status
[11:03:46.526] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:46.526] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:70:0) after 1m0s
[11:04:46.615] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:70:0)
[11:04:46.615] Timer tick, asking Buildbucket for the build status
[11:04:47.530] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:47.530] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:71:0) after 1m0s
[11:05:47.571] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:71:0)
[11:05:47.571] Timer tick, asking Buildbucket for the build status
[11:05:47.725] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:47.725] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:72:0) after 1m0s
[11:06:47.769] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:72:0)
[11:06:47.770] Timer tick, asking Buildbucket for the build status
[11:06:48.148] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:48.148] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:73:0) after 1m0s
[11:07:48.204] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:73:0)
[11:07:48.204] Timer tick, asking Buildbucket for the build status
[11:07:49.137] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:49.137] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:74:0) after 1m0s
[11:08:49.198] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:74:0)
[11:08:49.198] Timer tick, asking Buildbucket for the build status
[11:08:49.511] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:49.511] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:75:0) after 1m0s
[11:09:49.621] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:75:0)
[11:09:49.621] Timer tick, asking Buildbucket for the build status
[11:09:50.201] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:50.201] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:76:0) after 1m0s
[11:10:50.262] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:76:0)
[11:10:50.262] Timer tick, asking Buildbucket for the build status
[11:10:50.614] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:50.614] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:77:0) after 1m0s
[11:11:50.659] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:77:0)
[11:11:50.659] Timer tick, asking Buildbucket for the build status
[11:11:51.312] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:51.312] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:78:0) after 1m0s
[11:12:51.372] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:78:0)
[11:12:51.372] Timer tick, asking Buildbucket for the build status
[11:12:51.686] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:51.686] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:79:0) after 1m0s
[11:13:51.728] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:79:0)
[11:13:51.728] Timer tick, asking Buildbucket for the build status
[11:13:52.082] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:52.082] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:80:0) after 1m0s
[11:14:52.126] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:80:0)
[11:14:52.126] Timer tick, asking Buildbucket for the build status
[11:14:52.548] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:52.548] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:81:0) after 1m0s
[11:15:52.604] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:81:0)
[11:15:52.604] Timer tick, asking Buildbucket for the build status
[11:15:52.764] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:52.764] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:82:0) after 1m0s
[11:16:52.816] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:82:0)
[11:16:52.816] Timer tick, asking Buildbucket for the build status
[11:16:53.630] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:53.630] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:83:0) after 1m0s
[11:17:53.672] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:83:0)
[11:17:53.672] Timer tick, asking Buildbucket for the build status
[11:17:53.824] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:53.824] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:84:0) after 1m0s
[11:18:53.853] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:84:0)
[11:18:53.853] Timer tick, asking Buildbucket for the build status
[11:18:54.667] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:54.667] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:85:0) after 1m0s
[11:19:54.729] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:85:0)
[11:19:54.729] Timer tick, asking Buildbucket for the build status
[11:19:55.250] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:55.250] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:86:0) after 1m0s
[11:20:55.313] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:86:0)
[11:20:55.313] Timer tick, asking Buildbucket for the build status
[11:20:55.744] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:55.744] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:87:0) after 1m0s
[11:21:55.895] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:87:0)
[11:21:55.895] Timer tick, asking Buildbucket for the build status
[11:21:56.095] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:56.095] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:88:0) after 1m0s
[11:22:56.125] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:88:0)
[11:22:56.125] Timer tick, asking Buildbucket for the build status
[11:22:56.861] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:56.861] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:89:0) after 1m0s
[11:23:56.838] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:89:0)
[11:23:56.838] Timer tick, asking Buildbucket for the build status
[11:23:57.350] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:57.350] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:90:0) after 1m0s
[11:24:57.416] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:90:0)
[11:24:57.416] Timer tick, asking Buildbucket for the build status
[11:24:58.467] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:58.467] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:91:0) after 1m0s
[11:25:58.625] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:91:0)
[11:25:58.625] Timer tick, asking Buildbucket for the build status
[11:25:58.804] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:58.804] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:92:0) after 1m0s
[11:26:58.830] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:92:0)
[11:26:58.830] Timer tick, asking Buildbucket for the build status
[11:26:59.037] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:59.037] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:93:0) after 1m0s
[11:27:59.098] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:93:0)
[11:27:59.098] Timer tick, asking Buildbucket for the build status
[11:28:00.958] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:00.959] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:94:0) after 1m0s
[11:29:00.989] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:94:0)
[11:29:00.989] Timer tick, asking Buildbucket for the build status
[11:29:01.207] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:01.207] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:95:0) after 1m0s
[11:30:01.320] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:95:0)
[11:30:01.320] Timer tick, asking Buildbucket for the build status
[11:30:01.833] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:01.833] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:96:0) after 1m0s
[11:31:01.867] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:96:0)
[11:31:01.867] Timer tick, asking Buildbucket for the build status
[11:31:02.018] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:02.018] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:97:0) after 1m0s
[11:32:02.142] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:97:0)
[11:32:02.142] Timer tick, asking Buildbucket for the build status
[11:32:03.239] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:03.239] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:98:0) after 1m0s
[11:33:03.433] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:98:0)
[11:33:03.433] Timer tick, asking Buildbucket for the build status
[11:33:03.624] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:03.624] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:99:0) after 1m0s
[11:34:03.761] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:99:0)
[11:34:03.761] Timer tick, asking Buildbucket for the build status
[11:34:04.233] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:04.233] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:100:0) after 1m0s
[11:35:04.713] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:100:0)
[11:35:04.713] Timer tick, asking Buildbucket for the build status
[11:35:05.985] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:05.985] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:101:0) after 1m0s
[11:36:06.210] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:101:0)
[11:36:06.210] Timer tick, asking Buildbucket for the build status
[11:36:06.746] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:06.746] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:102:0) after 1m0s
[11:37:07.006] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:102:0)
[11:37:07.006] Timer tick, asking Buildbucket for the build status
[11:37:07.415] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:07.415] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:103:0) after 1m0s
[11:38:07.495] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:103:0)
[11:38:07.495] Timer tick, asking Buildbucket for the build status
[11:38:07.632] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:07.632] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:104:0) after 1m0s
[11:39:07.660] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:104:0)
[11:39:07.660] Timer tick, asking Buildbucket for the build status
[11:39:07.838] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:07.838] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:105:0) after 1m0s
[11:40:07.975] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:105:0)
[11:40:07.975] Timer tick, asking Buildbucket for the build status
[11:40:08.137] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:08.137] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:106:0) after 1m0s
[11:41:08.217] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:106:0)
[11:41:08.217] Timer tick, asking Buildbucket for the build status
[11:41:08.792] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:08.792] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:107:0) after 1m0s
[11:42:08.916] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:107:0)
[11:42:08.916] Timer tick, asking Buildbucket for the build status
[11:42:09.661] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:09.661] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:108:0) after 1m0s
[11:43:09.679] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:108:0)
[11:43:09.679] Timer tick, asking Buildbucket for the build status
[11:43:10.309] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:10.309] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:109:0) after 1m0s
[11:44:10.337] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:109:0)
[11:44:10.337] Timer tick, asking Buildbucket for the build status
[11:44:11.026] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:11.026] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:110:0) after 1m0s
[11:45:11.095] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:110:0)
[11:45:11.095] Timer tick, asking Buildbucket for the build status
[11:45:11.950] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:11.950] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:111:0) after 1m0s
[11:46:11.978] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:111:0)
[11:46:11.978] Timer tick, asking Buildbucket for the build status
[11:46:12.193] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:12.193] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:112:0) after 1m0s
[11:47:12.248] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:112:0)
[11:47:12.248] Timer tick, asking Buildbucket for the build status
[11:47:12.448] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:12.449] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:113:0) after 1m0s
[11:48:12.500] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:113:0)
[11:48:12.500] Timer tick, asking Buildbucket for the build status
[11:48:12.725] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:12.725] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:114:0) after 1m0s
[11:49:12.817] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:114:0)
[11:49:12.817] Timer tick, asking Buildbucket for the build status
[11:49:12.970] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:12.970] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:115:0) after 1m0s
[11:50:13.089] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:115:0)
[11:50:13.089] Timer tick, asking Buildbucket for the build status
[11:50:13.300] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:13.300] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:116:0) after 1m0s
[11:51:13.346] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:116:0)
[11:51:13.346] Timer tick, asking Buildbucket for the build status
[11:51:17.902] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:17.902] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:117:0) after 1m0s
[11:52:17.952] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:117:0)
[11:52:17.952] Timer tick, asking Buildbucket for the build status
[11:52:18.838] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:18.838] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:118:0) after 1m0s
[11:53:18.880] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:118:0)
[11:53:18.880] Timer tick, asking Buildbucket for the build status
[11:53:19.033] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:19.033] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:119:0) after 1m0s
[11:54:19.192] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:119:0)
[11:54:19.192] Timer tick, asking Buildbucket for the build status
[11:54:19.401] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:19.401] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:120:0) after 1m0s
[11:55:19.472] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:120:0)
[11:55:19.472] Timer tick, asking Buildbucket for the build status
[11:55:20.823] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:20.823] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:121:0) after 1m0s
[11:56:20.861] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:121:0)
[11:56:20.861] Timer tick, asking Buildbucket for the build status
[11:56:21.039] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:21.039] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:122:0) after 1m0s
[11:57:21.224] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:122:0)
[11:57:21.224] Timer tick, asking Buildbucket for the build status
[11:57:21.737] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:21.737] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:123:0) after 1m0s
[11:58:21.774] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:123:0)
[11:58:21.774] Timer tick, asking Buildbucket for the build status
[11:58:22.534] Build 8904523813500747488: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:22.534] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069953875523358672:124:0) after 1m0s
[11:58:43.459] Received PubSub notification, asking Buildbucket for the build status
[11:58:43.953] Build 8904523813500747488: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[11:58:43.953] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1566388722864287",
"created_by": "project:chromium",
"created_ts": "1566381361444914",
"failure_reason": "BUILD_FAILURE",
"id": "8904523813500747488",
"parameters_json": "{\"builder_name\": \"ToTiOS\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"ae6e7c3488ae126827e7da170d7cf84a0b142aca\"}}",
"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\": 9550, \"got_angle_revision\": \"ed4906f89d3dc1119de5bd6e7fbe24cf3104bcba\", \"got_dawn_revision\": \"ae2b9f4720a7286e001d63939b9deaf2731ccc9f\", \"got_nacl_revision\": \"058a26f57015051953a727add26e7e26348b9558\", \"got_revision\": \"ae6e7c3488ae126827e7da170d7cf84a0b142aca\", \"got_revision_cp\": \"refs/heads/master@{#688933}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"941e663bad8b353c885e3ee775f4d5c96878ecd4\", \"got_v8_revision_cp\": \"refs/heads/7.8.138@{#1}\", \"got_webrtc_revision\": \"364b2673c037b0cd0564e84ccbbf698dd3b60542\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28921}\", \"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\": \"ae6e7c3488ae126827e7da170d7cf84a0b142aca\", \"swarm_hashes\": {\"0\": \"112dc9d255b67785737a00233b0ed57af72fbdc1\", \"1\": \"b029b34ecd96c268d465111610a952b501ed136b\", \"10\": \"4e385ff18a20b736a9706576eeddcaf444a04f4a\", \"11\": \"b9d3f95f64b45845ceba4e4f6eea1ec20889dfc7\", \"12\": \"18c26dcf8b721cc0e13d5a7318c6e10695dfc4ea\", \"13\": \"d7f8c6d9814743d782efb9b8c04622c2e0b5b898\", \"14\": \"041829c0874c070f23dc22a4bf644c2f97b13a71\", \"15\": \"7ed49c87f6fa465b3b7c21ecc92faf10a45562f9\", \"16\": \"30169803e1150bb587ce2d7f77ee5c39d1719d1b\", \"2\": \"6cfcb40a84ef85cc47ebfc7325b71384953a2569\", \"3\": \"3a8e55333bc73dbf2bf7f9c51bacc02368c90a44\", \"4\": \"c3fb4da2955aad0ef20e37d7442be431da51ab65\", \"5\": \"bd224a2c3259fd00417e742ca6ff906ff41c04b0\", \"6\": \"e58275e15d6a6f45184df3443f0b412a7ccae23d\", \"7\": \"7d38824a29321da8e07013e79090967446e0aca3\", \"8\": \"fba3389f9426d50aa969f07d679c96cf38a561ae\", \"9\": \"93e6191d4278f7d01540ef7b66ac6683223bae4b\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4a4feefdd7e355061708c01720b06a0ca8c49ffc9968a2ebc674541738676b8f_v2\", \"builder_69caa34b8f39daac28a821cac2619730401d46a6479812cc34796e3bf180418a_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": "1566381548928445",
"status": "COMPLETED",
"status_changed_ts": "1566381549797498",
"tags": [
"build_address:luci.chromium.ci/ToTiOS/9550",
"builder:ToTiOS",
"buildset:commit/git/ae6e7c3488ae126827e7da170d7cf84a0b142aca",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ae6e7c3488ae126827e7da170d7cf84a0b142aca",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9069953875523358672",
"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/8904523813500747488/+/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:46cc6c54d4826610",
"user_agent:luci-scheduler"
],
"updated_ts": "1566388723014129",
"url": "https://ci.chromium.org/b/8904523813500747488",
"utcnow_ts": "1566388723941532"
}
}
[11:58:43.953] Invocation finished in 2h2m43.821321221s with status FAILED