[04:09:10.936] New invocation is queued and will start shortly
[04:09:12.066] Starting the invocation (attempt 1)
[04:09:12.123] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:09:12.123] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:09:12.123] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9056295553640659504",
"parameters_json": "{\"builder_name\":\"ToTiOS\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a3f7b1b4a6e7829a7ffcb78f73407337b6334853\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:ToTiOS",
"scheduler_invocation_id:9056295553640659504",
"scheduler_job_id:chromium/ToTiOS",
"user_agent:luci-scheduler",
"buildset:commit/git/a3f7b1b4a6e7829a7ffcb78f73407337b6334853",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a3f7b1b4a6e7829a7ffcb78f73407337b6334853",
"gitiles_ref:refs/heads/master"
]
}
[04:09:13.321] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1579406952391486",
"id": "8890865491448241520",
"parameters_json": "{\"builder_name\": \"ToTiOS\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a3f7b1b4a6e7829a7ffcb78f73407337b6334853\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1579406952977498",
"tags": [
"build_address:luci.chromium.ci/ToTiOS/11963",
"builder:ToTiOS",
"buildset:commit/git/a3f7b1b4a6e7829a7ffcb78f73407337b6334853",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a3f7b1b4a6e7829a7ffcb78f73407337b6334853",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9056295553640659504",
"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/8890865491448241520/+/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": "1579406952977636",
"url": "https://ci.chromium.org/b/8890865491448241520",
"utcnow_ts": "1579406953311120"
}
}
[04:09:13.321] Task URL: https://ci.chromium.org/b/8890865491448241520
[04:09:13.321] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:2:0) after 1m0s
[04:10:13.344] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:2:0)
[04:10:13.344] Timer tick, asking Buildbucket for the build status
[04:10:13.981] Build 8890865491448241520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:10:13.981] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:3:0) after 1m0s
[04:11:14.001] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:3:0)
[04:11:14.001] Timer tick, asking Buildbucket for the build status
[04:11:14.209] Build 8890865491448241520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:11:14.209] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:4:0) after 1m0s
[04:12:14.240] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:4:0)
[04:12:14.240] Timer tick, asking Buildbucket for the build status
[04:12:15.443] Build 8890865491448241520: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[04:12:15.443] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:5:0) after 1m0s
[04:12:40.973] Received PubSub notification, asking Buildbucket for the build status
[04:12:41.136] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:15.514] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:5:0)
[04:13:15.514] Timer tick, asking Buildbucket for the build status
[04:13:15.894] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:15.894] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:7:0) after 1m0s
[04:14:15.946] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:7:0)
[04:14:15.946] Timer tick, asking Buildbucket for the build status
[04:14:16.113] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:16.113] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:8:0) after 1m0s
[04:15:16.157] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:8:0)
[04:15:16.157] Timer tick, asking Buildbucket for the build status
[04:15:16.544] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:16.544] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:9:0) after 1m0s
[04:16:16.612] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:9:0)
[04:16:16.612] Timer tick, asking Buildbucket for the build status
[04:16:16.883] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:16.883] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:10:0) after 1m0s
[04:17:16.904] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:10:0)
[04:17:16.904] Timer tick, asking Buildbucket for the build status
[04:17:17.067] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:17.067] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:11:0) after 1m0s
[04:18:17.143] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:11:0)
[04:18:17.143] Timer tick, asking Buildbucket for the build status
[04:18:18.366] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:18.366] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:12:0) after 1m0s
[04:19:18.393] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:12:0)
[04:19:18.393] Timer tick, asking Buildbucket for the build status
[04:19:18.598] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:18.598] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:13:0) after 1m0s
[04:20:18.616] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:13:0)
[04:20:18.616] Timer tick, asking Buildbucket for the build status
[04:20:18.866] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:18.866] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:14:0) after 1m0s
[04:21:18.936] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:14:0)
[04:21:18.936] Timer tick, asking Buildbucket for the build status
[04:21:19.221] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:19.221] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:15:0) after 1m0s
[04:22:19.251] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:15:0)
[04:22:19.251] Timer tick, asking Buildbucket for the build status
[04:22:19.510] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:19.510] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:16:0) after 1m0s
[04:23:19.555] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:16:0)
[04:23:19.555] Timer tick, asking Buildbucket for the build status
[04:23:19.695] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:19.695] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:17:0) after 1m0s
[04:24:19.716] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:17:0)
[04:24:19.716] Timer tick, asking Buildbucket for the build status
[04:24:20.074] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:20.074] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:18:0) after 1m0s
[04:25:20.191] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:18:0)
[04:25:20.191] Timer tick, asking Buildbucket for the build status
[04:25:20.333] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:20.333] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:19:0) after 1m0s
[04:26:20.375] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:19:0)
[04:26:20.375] Timer tick, asking Buildbucket for the build status
[04:26:22.050] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:22.050] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:20:0) after 1m0s
[04:27:22.082] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:20:0)
[04:27:22.082] Timer tick, asking Buildbucket for the build status
[04:27:22.337] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:22.337] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:21:0) after 1m0s
[04:28:22.814] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:21:0)
[04:28:22.814] Timer tick, asking Buildbucket for the build status
[04:28:24.067] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:24.067] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:22:0) after 1m0s
[04:29:24.350] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:22:0)
[04:29:24.350] Timer tick, asking Buildbucket for the build status
[04:29:24.849] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:24.849] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:23:0) after 1m0s
[04:30:25.187] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:23:0)
[04:30:25.187] Timer tick, asking Buildbucket for the build status
[04:30:25.674] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:25.674] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:24:0) after 1m0s
[04:31:25.727] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:24:0)
[04:31:25.727] Timer tick, asking Buildbucket for the build status
[04:31:26.435] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:26.435] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:25:0) after 1m0s
[04:32:26.721] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:25:0)
[04:32:26.721] Timer tick, asking Buildbucket for the build status
[04:32:26.942] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:26.942] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:26:0) after 1m0s
[04:33:27.065] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:26:0)
[04:33:27.065] Timer tick, asking Buildbucket for the build status
[04:33:27.473] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:27.474] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:27:0) after 1m0s
[04:34:27.509] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:27:0)
[04:34:27.509] Timer tick, asking Buildbucket for the build status
[04:34:27.715] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:27.715] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:28:0) after 1m0s
[04:35:28.046] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:28:0)
[04:35:28.046] Timer tick, asking Buildbucket for the build status
[04:35:28.217] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:28.217] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:29:0) after 1m0s
[04:36:28.462] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:29:0)
[04:36:28.462] Timer tick, asking Buildbucket for the build status
[04:36:29.088] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:29.088] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:30:0) after 1m0s
[04:37:29.284] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:30:0)
[04:37:29.284] Timer tick, asking Buildbucket for the build status
[04:37:29.426] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:29.426] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:31:0) after 1m0s
[04:38:29.787] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:31:0)
[04:38:29.787] Timer tick, asking Buildbucket for the build status
[04:38:30.349] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:30.349] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:32:0) after 1m0s
[04:39:30.380] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:32:0)
[04:39:30.380] Timer tick, asking Buildbucket for the build status
[04:39:30.525] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:30.525] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:33:0) after 1m0s
[04:40:30.594] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:33:0)
[04:40:30.594] Timer tick, asking Buildbucket for the build status
[04:40:30.742] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:30.742] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:34:0) after 1m0s
[04:41:30.897] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:34:0)
[04:41:30.897] Timer tick, asking Buildbucket for the build status
[04:41:31.162] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:31.162] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:35:0) after 1m0s
[04:42:31.363] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:35:0)
[04:42:31.363] Timer tick, asking Buildbucket for the build status
[04:42:31.764] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:31.764] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:36:0) after 1m0s
[04:43:31.823] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:36:0)
[04:43:31.823] Timer tick, asking Buildbucket for the build status
[04:43:32.236] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:32.237] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:37:0) after 1m0s
[04:44:32.400] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:37:0)
[04:44:32.400] Timer tick, asking Buildbucket for the build status
[04:44:32.686] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:32.686] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:38:0) after 1m0s
[04:45:32.712] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:38:0)
[04:45:32.713] Timer tick, asking Buildbucket for the build status
[04:45:33.575] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:33.575] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:39:0) after 1m0s
[04:46:33.696] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:39:0)
[04:46:33.696] Timer tick, asking Buildbucket for the build status
[04:46:33.856] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:33.856] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:40:0) after 1m0s
[04:47:34.006] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:40:0)
[04:47:34.006] Timer tick, asking Buildbucket for the build status
[04:47:34.163] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:34.163] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:41:0) after 1m0s
[04:48:34.266] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:41:0)
[04:48:34.266] Timer tick, asking Buildbucket for the build status
[04:48:34.397] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:34.397] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:42:0) after 1m0s
[04:49:34.417] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:42:0)
[04:49:34.417] Timer tick, asking Buildbucket for the build status
[04:49:34.614] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:34.614] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:43:0) after 1m0s
[04:50:34.598] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:43:0)
[04:50:34.598] Timer tick, asking Buildbucket for the build status
[04:50:35.076] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:35.076] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:44:0) after 1m0s
[04:51:35.101] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:44:0)
[04:51:35.101] Timer tick, asking Buildbucket for the build status
[04:51:35.482] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:35.482] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:45:0) after 1m0s
[04:52:35.608] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:45:0)
[04:52:35.608] Timer tick, asking Buildbucket for the build status
[04:52:35.766] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:35.766] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:46:0) after 1m0s
[04:53:35.980] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:46:0)
[04:53:35.980] Timer tick, asking Buildbucket for the build status
[04:53:36.223] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:36.223] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:47:0) after 1m0s
[04:54:36.356] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:47:0)
[04:54:36.356] Timer tick, asking Buildbucket for the build status
[04:54:36.676] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:36.676] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:48:0) after 1m0s
[04:55:36.950] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:48:0)
[04:55:36.950] Timer tick, asking Buildbucket for the build status
[04:55:37.908] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:37.908] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:49:0) after 1m0s
[04:56:38.095] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:49:0)
[04:56:38.095] Timer tick, asking Buildbucket for the build status
[04:56:38.224] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:38.224] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:50:0) after 1m0s
[04:57:38.287] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:50:0)
[04:57:38.287] Timer tick, asking Buildbucket for the build status
[04:57:38.575] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:38.575] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:51:0) after 1m0s
[04:58:38.693] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:51:0)
[04:58:38.693] Timer tick, asking Buildbucket for the build status
[04:58:38.838] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:38.838] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:52:0) after 1m0s
[04:59:38.862] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:52:0)
[04:59:38.862] Timer tick, asking Buildbucket for the build status
[04:59:39.241] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:39.241] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:53:0) after 1m0s
[05:00:39.267] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:53:0)
[05:00:39.267] Timer tick, asking Buildbucket for the build status
[05:00:39.701] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:39.701] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:54:0) after 1m0s
[05:01:39.890] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:54:0)
[05:01:39.890] Timer tick, asking Buildbucket for the build status
[05:01:40.065] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:40.065] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:55:0) after 1m0s
[05:02:40.108] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:55:0)
[05:02:40.108] Timer tick, asking Buildbucket for the build status
[05:02:40.422] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:40.422] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:56:0) after 1m0s
[05:03:40.539] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:56:0)
[05:03:40.539] Timer tick, asking Buildbucket for the build status
[05:03:41.648] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:41.648] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:57:0) after 1m0s
[05:04:41.697] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:57:0)
[05:04:41.697] Timer tick, asking Buildbucket for the build status
[05:04:41.868] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:41.868] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:58:0) after 1m0s
[05:05:41.898] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:58:0)
[05:05:41.898] Timer tick, asking Buildbucket for the build status
[05:05:42.071] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:42.071] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:59:0) after 1m0s
[05:06:42.166] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:59:0)
[05:06:42.166] Timer tick, asking Buildbucket for the build status
[05:06:42.336] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:42.336] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:60:0) after 1m0s
[05:07:42.361] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:60:0)
[05:07:42.361] Timer tick, asking Buildbucket for the build status
[05:07:42.599] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:42.599] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:61:0) after 1m0s
[05:08:42.681] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:61:0)
[05:08:42.681] Timer tick, asking Buildbucket for the build status
[05:08:43.510] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:43.510] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:62:0) after 1m0s
[05:09:43.531] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:62:0)
[05:09:43.531] Timer tick, asking Buildbucket for the build status
[05:09:43.680] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:43.680] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:63:0) after 1m0s
[05:10:43.705] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:63:0)
[05:10:43.705] Timer tick, asking Buildbucket for the build status
[05:10:43.915] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:43.915] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:64:0) after 1m0s
[05:11:44.034] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:64:0)
[05:11:44.034] Timer tick, asking Buildbucket for the build status
[05:11:44.279] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:44.279] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:65:0) after 1m0s
[05:12:44.304] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:65:0)
[05:12:44.304] Timer tick, asking Buildbucket for the build status
[05:12:44.459] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:44.459] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:66:0) after 1m0s
[05:13:44.491] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:66:0)
[05:13:44.491] Timer tick, asking Buildbucket for the build status
[05:13:44.702] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:44.702] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:67:0) after 1m0s
[05:14:44.822] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:67:0)
[05:14:44.822] Timer tick, asking Buildbucket for the build status
[05:14:45.222] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:45.222] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:68:0) after 1m0s
[05:15:45.254] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:68:0)
[05:15:45.254] Timer tick, asking Buildbucket for the build status
[05:15:45.651] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:45.651] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:69:0) after 1m0s
[05:16:45.678] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:69:0)
[05:16:45.678] Timer tick, asking Buildbucket for the build status
[05:16:46.368] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:46.368] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:70:0) after 1m0s
[05:17:46.439] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:70:0)
[05:17:46.439] Timer tick, asking Buildbucket for the build status
[05:17:46.995] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:46.995] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:71:0) after 1m0s
[05:18:47.207] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:71:0)
[05:18:47.207] Timer tick, asking Buildbucket for the build status
[05:18:47.386] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:47.386] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:72:0) after 1m0s
[05:19:47.483] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:72:0)
[05:19:47.483] Timer tick, asking Buildbucket for the build status
[05:19:47.670] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:47.670] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:73:0) after 1m0s
[05:20:47.796] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:73:0)
[05:20:47.796] Timer tick, asking Buildbucket for the build status
[05:20:48.200] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:48.200] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:74:0) after 1m0s
[05:21:48.350] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:74:0)
[05:21:48.350] Timer tick, asking Buildbucket for the build status
[05:21:48.499] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:48.499] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:75:0) after 1m0s
[05:22:48.515] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:75:0)
[05:22:48.515] Timer tick, asking Buildbucket for the build status
[05:22:49.296] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:49.296] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:76:0) after 1m0s
[05:23:49.379] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:76:0)
[05:23:49.379] Timer tick, asking Buildbucket for the build status
[05:23:49.721] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:49.721] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:77:0) after 1m0s
[05:24:49.759] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:77:0)
[05:24:49.759] Timer tick, asking Buildbucket for the build status
[05:24:50.579] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:50.579] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:78:0) after 1m0s
[05:25:50.603] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:78:0)
[05:25:50.603] Timer tick, asking Buildbucket for the build status
[05:25:54.116] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:54.116] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:79:0) after 1m0s
[05:26:54.184] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:79:0)
[05:26:54.184] Timer tick, asking Buildbucket for the build status
[05:26:54.732] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:54.732] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:80:0) after 1m0s
[05:27:54.855] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:80:0)
[05:27:54.855] Timer tick, asking Buildbucket for the build status
[05:27:55.251] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:55.251] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:81:0) after 1m0s
[05:28:55.276] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:81:0)
[05:28:55.276] Timer tick, asking Buildbucket for the build status
[05:28:56.205] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:56.205] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:82:0) after 1m0s
[05:29:56.230] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:82:0)
[05:29:56.230] Timer tick, asking Buildbucket for the build status
[05:29:56.385] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:56.386] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:83:0) after 1m0s
[05:30:56.410] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:83:0)
[05:30:56.410] Timer tick, asking Buildbucket for the build status
[05:30:56.568] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:56.568] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:84:0) after 1m0s
[05:31:56.593] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:84:0)
[05:31:56.593] Timer tick, asking Buildbucket for the build status
[05:31:56.782] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:56.783] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:85:0) after 1m0s
[05:32:56.827] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:85:0)
[05:32:56.827] Timer tick, asking Buildbucket for the build status
[05:32:58.292] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:58.292] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:86:0) after 1m0s
[05:33:58.387] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:86:0)
[05:33:58.387] Timer tick, asking Buildbucket for the build status
[05:33:58.525] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:58.525] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:87:0) after 1m0s
[05:34:58.584] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:87:0)
[05:34:58.584] Timer tick, asking Buildbucket for the build status
[05:34:58.843] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:58.843] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:88:0) after 1m0s
[05:35:58.889] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:88:0)
[05:35:58.889] Timer tick, asking Buildbucket for the build status
[05:35:59.288] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:59.288] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:89:0) after 1m0s
[05:36:59.341] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:89:0)
[05:36:59.341] Timer tick, asking Buildbucket for the build status
[05:36:59.939] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:59.939] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:90:0) after 1m0s
[05:38:00.063] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:90:0)
[05:38:00.063] Timer tick, asking Buildbucket for the build status
[05:38:00.289] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:00.289] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:91:0) after 1m0s
[05:39:00.380] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:91:0)
[05:39:00.380] Timer tick, asking Buildbucket for the build status
[05:39:00.542] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:00.542] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:92:0) after 1m0s
[05:40:00.712] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:92:0)
[05:40:00.712] Timer tick, asking Buildbucket for the build status
[05:40:00.885] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:00.885] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:93:0) after 1m0s
[05:41:01.353] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:93:0)
[05:41:01.353] Timer tick, asking Buildbucket for the build status
[05:41:01.729] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:01.729] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:94:0) after 1m0s
[05:42:01.936] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:94:0)
[05:42:01.936] Timer tick, asking Buildbucket for the build status
[05:42:02.132] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:02.132] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:95:0) after 1m0s
[05:43:02.293] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:95:0)
[05:43:02.293] Timer tick, asking Buildbucket for the build status
[05:43:02.437] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:02.437] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:96:0) after 1m0s
[05:44:02.469] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:96:0)
[05:44:02.469] Timer tick, asking Buildbucket for the build status
[05:44:04.103] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:04.103] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:97:0) after 1m0s
[05:45:04.129] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:97:0)
[05:45:04.129] Timer tick, asking Buildbucket for the build status
[05:45:04.287] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:04.287] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:98:0) after 1m0s
[05:46:04.315] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:98:0)
[05:46:04.315] Timer tick, asking Buildbucket for the build status
[05:46:04.652] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:04.652] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:99:0) after 1m0s
[05:47:04.797] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:99:0)
[05:47:04.797] Timer tick, asking Buildbucket for the build status
[05:47:04.942] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:04.942] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:100:0) after 1m0s
[05:48:05.076] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:100:0)
[05:48:05.076] Timer tick, asking Buildbucket for the build status
[05:48:05.644] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:05.644] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:101:0) after 1m0s
[05:49:05.827] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:101:0)
[05:49:05.827] Timer tick, asking Buildbucket for the build status
[05:49:06.052] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:06.052] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:102:0) after 1m0s
[05:50:06.174] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:102:0)
[05:50:06.174] Timer tick, asking Buildbucket for the build status
[05:50:06.320] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:06.320] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:103:0) after 1m0s
[05:51:06.348] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:103:0)
[05:51:06.348] Timer tick, asking Buildbucket for the build status
[05:51:06.533] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:06.533] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:104:0) after 1m0s
[05:52:06.581] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:104:0)
[05:52:06.581] Timer tick, asking Buildbucket for the build status
[05:52:06.723] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:06.723] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:105:0) after 1m0s
[05:53:06.797] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:105:0)
[05:53:06.798] Timer tick, asking Buildbucket for the build status
[05:53:07.512] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:07.512] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:106:0) after 1m0s
[05:54:07.539] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:106:0)
[05:54:07.539] Timer tick, asking Buildbucket for the build status
[05:54:07.705] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:07.705] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:107:0) after 1m0s
[05:55:07.740] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:107:0)
[05:55:07.740] Timer tick, asking Buildbucket for the build status
[05:55:07.959] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:07.959] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:108:0) after 1m0s
[05:56:07.970] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:108:0)
[05:56:07.970] Timer tick, asking Buildbucket for the build status
[05:56:08.138] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:08.138] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:109:0) after 1m0s
[05:57:08.171] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:109:0)
[05:57:08.171] Timer tick, asking Buildbucket for the build status
[05:57:10.215] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:10.215] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:110:0) after 1m0s
[05:58:10.297] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:110:0)
[05:58:10.297] Timer tick, asking Buildbucket for the build status
[05:58:10.486] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:10.486] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:111:0) after 1m0s
[05:59:10.544] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:111:0)
[05:59:10.544] Timer tick, asking Buildbucket for the build status
[05:59:10.835] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:10.835] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:112:0) after 1m0s
[06:00:10.863] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:112:0)
[06:00:10.863] Timer tick, asking Buildbucket for the build status
[06:00:12.259] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:12.259] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:113:0) after 1m0s
[06:01:12.301] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:113:0)
[06:01:12.301] Timer tick, asking Buildbucket for the build status
[06:01:12.681] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:12.681] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:114:0) after 1m0s
[06:02:12.744] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:114:0)
[06:02:12.744] Timer tick, asking Buildbucket for the build status
[06:02:13.680] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:13.680] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:115:0) after 1m0s
[06:03:13.751] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:115:0)
[06:03:13.751] Timer tick, asking Buildbucket for the build status
[06:03:13.942] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:13.942] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:116:0) after 1m0s
[06:04:14.043] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:116:0)
[06:04:14.043] Timer tick, asking Buildbucket for the build status
[06:04:14.378] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:14.378] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:117:0) after 1m0s
[06:05:14.408] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:117:0)
[06:05:14.408] Timer tick, asking Buildbucket for the build status
[06:05:14.591] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:14.591] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:118:0) after 1m0s
[06:06:14.726] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:118:0)
[06:06:14.726] Timer tick, asking Buildbucket for the build status
[06:06:14.916] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:14.916] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:119:0) after 1m0s
[06:07:15.004] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:119:0)
[06:07:15.004] Timer tick, asking Buildbucket for the build status
[06:07:15.246] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:15.246] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:120:0) after 1m0s
[06:08:15.334] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:120:0)
[06:08:15.334] Timer tick, asking Buildbucket for the build status
[06:08:16.306] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:16.306] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:121:0) after 1m0s
[06:09:16.464] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:121:0)
[06:09:16.464] Timer tick, asking Buildbucket for the build status
[06:09:16.658] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:16.658] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:122:0) after 1m0s
[06:10:16.731] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:122:0)
[06:10:16.731] Timer tick, asking Buildbucket for the build status
[06:10:17.326] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:17.326] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:123:0) after 1m0s
[06:11:17.455] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:123:0)
[06:11:17.455] Timer tick, asking Buildbucket for the build status
[06:11:17.783] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:17.783] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:124:0) after 1m0s
[06:12:18.018] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:124:0)
[06:12:18.018] Timer tick, asking Buildbucket for the build status
[06:12:19.394] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:19.394] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:125:0) after 1m0s
[06:13:19.562] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:125:0)
[06:13:19.562] Timer tick, asking Buildbucket for the build status
[06:13:19.810] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:19.810] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:126:0) after 1m0s
[06:14:20.119] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:126:0)
[06:14:20.119] Timer tick, asking Buildbucket for the build status
[06:14:20.563] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:20.563] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:127:0) after 1m0s
[06:15:20.562] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:127:0)
[06:15:20.562] Timer tick, asking Buildbucket for the build status
[06:15:20.790] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:20.790] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:128:0) after 1m0s
[06:16:20.832] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:128:0)
[06:16:20.832] Timer tick, asking Buildbucket for the build status
[06:16:21.286] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:21.286] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:129:0) after 1m0s
[06:17:21.560] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:129:0)
[06:17:21.560] Timer tick, asking Buildbucket for the build status
[06:17:21.761] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:21.761] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:130:0) after 1m0s
[06:18:21.887] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:130:0)
[06:18:21.887] Timer tick, asking Buildbucket for the build status
[06:18:22.136] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:22.136] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:131:0) after 1m0s
[06:19:22.263] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:131:0)
[06:19:22.263] Timer tick, asking Buildbucket for the build status
[06:19:22.737] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:22.737] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:132:0) after 1m0s
[06:20:22.844] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:132:0)
[06:20:22.844] Timer tick, asking Buildbucket for the build status
[06:20:23.107] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:23.108] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:133:0) after 1m0s
[06:21:23.264] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:133:0)
[06:21:23.264] Timer tick, asking Buildbucket for the build status
[06:21:23.524] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:23.524] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:134:0) after 1m0s
[06:22:23.558] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:134:0)
[06:22:23.558] Timer tick, asking Buildbucket for the build status
[06:22:23.846] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:23.846] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:135:0) after 1m0s
[06:23:24.023] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:135:0)
[06:23:24.023] Timer tick, asking Buildbucket for the build status
[06:23:24.297] Build 8890865491448241520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:24.297] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9056295553640659504:136:0) after 1m0s
[06:23:58.020] Received PubSub notification, asking Buildbucket for the build status
[06:23:58.198] Build 8890865491448241520: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[06:23:58.198] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1579415037365777",
"created_by": "project:chromium",
"created_ts": "1579406952391486",
"failure_reason": "BUILD_FAILURE",
"id": "8890865491448241520",
"parameters_json": "{\"builder_name\": \"ToTiOS\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a3f7b1b4a6e7829a7ffcb78f73407337b6334853\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Failed ios_chrome_unittests (iPhone 6s iOS 12.2), ios_web_inttests (iPhone 6s iOS 12.2).\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"build283-m9\", \"branch\": \"refs/heads/master\", \"buildername\": \"ToTiOS\", \"buildnumber\": 11963, \"got_angle_revision\": \"579c59400f860d9f63510a5da9441bb7ccca422a\", \"got_dawn_revision\": \"d8f8c29bb25a5a1658da37aae05b9b4cd4353640\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"a3f7b1b4a6e7829a7ffcb78f73407337b6334853\", \"got_revision_cp\": \"refs/heads/master@{#733182}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"11cb891a01a2b47cc2135ef19ca18046662476e0\", \"got_v8_revision\": \"3bb6c2dd702c09576d665319c09a89242710a496\", \"got_v8_revision_cp\": \"refs/heads/8.1.222@{#1}\", \"got_webrtc_revision\": \"c380e97ee62a3e1a75290bfb0e438ca267c6237c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30312}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.clang\", \"path_config\": \"generic\", \"perf_dashboard_machine_group\": \"ChromiumClang\", \"recipe\": \"ios/unified_builder_tester\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a3f7b1b4a6e7829a7ffcb78f73407337b6334853\", \"swarm_hashes\": {\"0\": \"af6699a5d7dad77c62f2eb26e738e16d22a3c083\", \"1\": \"aa48d1a29025823865b18169c7bb37c1ca877dfb\", \"10\": \"f3ce6730d6924c2cb70455cd21a9e942ec95513d\", \"11\": \"347bb9d011c751572627e2fdc282fd8822821b7b\", \"12\": \"b90fbaebd4c68c375b7ae3274ac26c06d3fb925c\", \"13\": \"c101cdebe12083809f390993f2c2547880c4e80a\", \"14\": \"e8fee48f46aacef9723948e64a3d9418f4eb184f\", \"15\": \"fcee43202d7c76b9bca4eece93d41fe0902bd1be\", \"16\": \"f540ec5731e9d362f97d602f6e9169bf61ae4e1d\", \"2\": \"9f4ad429052e13569ba2c85d57966310266e2be2\", \"3\": \"8ca8a35645a1def221f7ea81e56ee61489e8f97f\", \"4\": \"44bdaf486990907a4e8f5dc93e2779e6e6d169a3\", \"5\": \"8cc42ffc568519706e40baa587e2b8f9b49ddc44\", \"6\": \"bcaf35a0a94ef8b7ec0c88c049a46773679a6aec\", \"7\": \"1a2b20398bf31503ddb274cd15e91edada67e753\", \"8\": \"376cca0fab0f014a66a6938537a9806f5cc4c1f1\", \"9\": \"299d1aaec12a98d5f40d075502180ff4f68aceed\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0c96c161cf7dd2e1c2b922319da904a0c0164c4adceed5a8844a45f1c516ad13_v2\", \"builder_4a4feefdd7e355061708c01720b06a0ca8c49ffc9968a2ebc674541738676b8f_v2\", \"builder_5372538a354f163a20d332d881c6c5d85e35654ac8a57c760866f12033f8a8a5_v2\", \"builder_69caa34b8f39daac28a821cac2619730401d46a6479812cc34796e3bf180418a_v2\", \"builder_ae5debd901c74ee0906916cb62621bceb2a8b657b42c2e2e9e89a0f6a2e5e53b_v2\", \"builder_d42b89eab403033f5a2a8102ec9c436397350cedb439d5e09b11874a5784adb4_v2\", \"git\", \"goma_v2\", \"vpython\", \"xcode_ios_11a1027\"], \"cores\": [\"24\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-E5-2697_v2\"], \"gce\": [\"0\"], \"gpu\": [\"1002\", \"1002:679e\", \"1002:679e-4.0.11-3.2.8\"], \"hidpi\": [\"0\"], \"id\": [\"build283-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\": [\"4760-d9a0e6d\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-atl\", \"us-atl-golo\", \"us-atl-golo-m9\"]}}, \"ui\": {\"info\": \"Failed ios_chrome_unittests (iPhone 6s iOS 12.2), ios_web_inttests (iPhone 6s iOS 12.2).\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1579407159956899",
"status": "COMPLETED",
"status_changed_ts": "1579407160365397",
"tags": [
"build_address:luci.chromium.ci/ToTiOS/11963",
"builder:ToTiOS",
"buildset:commit/git/a3f7b1b4a6e7829a7ffcb78f73407337b6334853",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a3f7b1b4a6e7829a7ffcb78f73407337b6334853",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9056295553640659504",
"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/8890865491448241520/+/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:49d4cf14b2f48e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1579415037624831",
"url": "https://ci.chromium.org/b/8890865491448241520",
"utcnow_ts": "1579415038189587"
}
}
[06:23:58.198] Invocation finished in 2h14m47.281429726s with status FAILED