[21:23:40.760] New invocation is queued and will start shortly
[21:23:42.212] Starting the invocation (attempt 1)
[21:23:42.352] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:23:42.353] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:23:42.353] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9069457625805492624",
"parameters_json": "{\"builder_name\":\"ToTiOS\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"e0bb634deb88ffd35335cf9e4af0f13de4a0bf31\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:ToTiOS",
"scheduler_invocation_id:9069457625805492624",
"scheduler_job_id:chromium/ToTiOS",
"user_agent:luci-scheduler",
"buildset:commit/git/e0bb634deb88ffd35335cf9e4af0f13de4a0bf31",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e0bb634deb88ffd35335cf9e4af0f13de4a0bf31",
"gitiles_ref:refs/heads/master"
]
}
[21:23:45.669] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1566854623463126",
"id": "8904027562306072240",
"parameters_json": "{\"builder_name\": \"ToTiOS\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"e0bb634deb88ffd35335cf9e4af0f13de4a0bf31\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1566854624982782",
"tags": [
"build_address:luci.chromium.ci/ToTiOS/9621",
"builder:ToTiOS",
"buildset:commit/git/e0bb634deb88ffd35335cf9e4af0f13de4a0bf31",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e0bb634deb88ffd35335cf9e4af0f13de4a0bf31",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9069457625805492624",
"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/8904027562306072240/+/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": "1566854624982881",
"url": "https://ci.chromium.org/b/8904027562306072240",
"utcnow_ts": "1566854625622707"
}
}
[21:23:45.669] Task URL: https://ci.chromium.org/b/8904027562306072240
[21:23:45.669] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:2:0) after 1m0s
[21:24:45.866] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:2:0)
[21:24:45.866] Timer tick, asking Buildbucket for the build status
[21:24:46.471] Build 8904027562306072240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:24:46.471] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:3:0) after 1m0s
[21:25:46.561] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:3:0)
[21:25:46.561] Timer tick, asking Buildbucket for the build status
[21:25:47.061] Build 8904027562306072240: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:25:47.061] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:4:0) after 1m0s
[21:26:33.942] Received PubSub notification, asking Buildbucket for the build status
[21:26:37.675] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:47.093] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:4:0)
[21:26:47.093] Timer tick, asking Buildbucket for the build status
[21:26:47.957] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:47.957] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:6:0) after 1m0s
[21:27:48.033] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:6:0)
[21:27:48.033] Timer tick, asking Buildbucket for the build status
[21:27:50.417] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:50.417] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:7:0) after 1m0s
[21:28:50.650] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:7:0)
[21:28:50.650] Timer tick, asking Buildbucket for the build status
[21:28:53.989] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:53.989] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:8:0) after 1m0s
[21:29:54.082] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:8:0)
[21:29:54.082] Timer tick, asking Buildbucket for the build status
[21:29:57.165] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:57.165] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:9:0) after 1m0s
[21:30:57.254] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:9:0)
[21:30:57.254] Timer tick, asking Buildbucket for the build status
[21:30:57.588] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:57.588] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:10:0) after 1m0s
[21:31:57.833] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:10:0)
[21:31:57.833] Timer tick, asking Buildbucket for the build status
[21:31:58.769] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:58.769] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:11:0) after 1m0s
[21:32:58.898] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:11:0)
[21:32:58.898] Timer tick, asking Buildbucket for the build status
[21:33:01.379] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:01.379] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:12:0) after 1m0s
[21:34:01.438] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:12:0)
[21:34:01.438] Timer tick, asking Buildbucket for the build status
[21:34:02.365] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:02.365] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:13:0) after 1m0s
[21:35:02.386] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:13:0)
[21:35:02.386] Timer tick, asking Buildbucket for the build status
[21:35:03.475] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:03.475] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:14:0) after 1m0s
[21:36:03.567] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:14:0)
[21:36:03.568] Timer tick, asking Buildbucket for the build status
[21:36:03.936] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:03.936] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:15:0) after 1m0s
[21:37:04.922] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:15:0)
[21:37:04.922] Timer tick, asking Buildbucket for the build status
[21:37:11.734] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:11.734] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:16:0) after 1m0s
[21:38:11.726] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:16:0)
[21:38:11.727] Timer tick, asking Buildbucket for the build status
[21:38:14.199] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:14.199] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:17:0) after 1m0s
[21:39:14.294] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:17:0)
[21:39:14.294] Timer tick, asking Buildbucket for the build status
[21:39:14.798] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:14.798] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:18:0) after 1m0s
[21:40:14.893] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:18:0)
[21:40:14.893] Timer tick, asking Buildbucket for the build status
[21:40:15.366] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:15.366] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:19:0) after 1m0s
[21:41:15.662] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:19:0)
[21:41:15.662] Timer tick, asking Buildbucket for the build status
[21:41:17.407] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:17.407] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:20:0) after 1m0s
[21:42:17.428] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:20:0)
[21:42:17.428] Timer tick, asking Buildbucket for the build status
[21:42:17.743] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:17.743] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:21:0) after 1m0s
[21:43:17.916] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:21:0)
[21:43:17.916] Timer tick, asking Buildbucket for the build status
[21:43:18.369] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:18.369] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:22:0) after 1m0s
[21:44:18.411] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:22:0)
[21:44:18.411] Timer tick, asking Buildbucket for the build status
[21:44:18.840] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:18.840] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:23:0) after 1m0s
[21:45:18.887] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:23:0)
[21:45:18.887] Timer tick, asking Buildbucket for the build status
[21:45:19.311] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:19.311] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:24:0) after 1m0s
[21:46:19.395] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:24:0)
[21:46:19.395] Timer tick, asking Buildbucket for the build status
[21:46:20.272] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:20.272] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:25:0) after 1m0s
[21:47:20.505] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:25:0)
[21:47:20.505] Timer tick, asking Buildbucket for the build status
[21:47:20.857] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:20.857] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:26:0) after 1m0s
[21:48:21.012] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:26:0)
[21:48:21.012] Timer tick, asking Buildbucket for the build status
[21:48:21.736] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:21.736] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:27:0) after 1m0s
[21:49:21.891] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:27:0)
[21:49:21.891] Timer tick, asking Buildbucket for the build status
[21:49:22.279] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:22.279] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:28:0) after 1m0s
[21:50:22.433] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:28:0)
[21:50:22.433] Timer tick, asking Buildbucket for the build status
[21:50:23.578] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:23.578] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:29:0) after 1m0s
[21:51:23.602] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:29:0)
[21:51:23.602] Timer tick, asking Buildbucket for the build status
[21:51:24.047] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:24.047] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:30:0) after 1m0s
[21:52:24.127] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:30:0)
[21:52:24.127] Timer tick, asking Buildbucket for the build status
[21:52:24.805] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:24.805] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:31:0) after 1m0s
[21:53:24.853] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:31:0)
[21:53:24.853] Timer tick, asking Buildbucket for the build status
[21:53:25.287] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:25.287] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:32:0) after 1m0s
[21:54:25.334] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:32:0)
[21:54:25.334] Timer tick, asking Buildbucket for the build status
[21:54:25.636] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:25.636] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:33:0) after 1m0s
[21:55:25.792] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:33:0)
[21:55:25.792] Timer tick, asking Buildbucket for the build status
[21:55:26.823] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:26.823] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:34:0) after 1m0s
[21:56:26.844] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:34:0)
[21:56:26.845] Timer tick, asking Buildbucket for the build status
[21:56:27.503] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:27.503] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:35:0) after 1m0s
[21:57:27.553] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:35:0)
[21:57:27.553] Timer tick, asking Buildbucket for the build status
[21:57:30.903] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:30.903] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:36:0) after 1m0s
[21:58:30.984] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:36:0)
[21:58:30.984] Timer tick, asking Buildbucket for the build status
[21:58:31.165] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:31.165] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:37:0) after 1m0s
[21:59:31.150] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:37:0)
[21:59:31.150] Timer tick, asking Buildbucket for the build status
[21:59:31.418] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:31.418] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:38:0) after 1m0s
[22:00:31.503] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:38:0)
[22:00:31.503] Timer tick, asking Buildbucket for the build status
[22:00:31.708] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:31.708] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:39:0) after 1m0s
[22:01:31.747] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:39:0)
[22:01:31.747] Timer tick, asking Buildbucket for the build status
[22:01:31.995] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:31.996] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:40:0) after 1m0s
[22:02:32.132] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:40:0)
[22:02:32.132] Timer tick, asking Buildbucket for the build status
[22:02:32.422] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:32.422] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:41:0) after 1m0s
[22:03:32.647] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:41:0)
[22:03:32.647] Timer tick, asking Buildbucket for the build status
[22:03:32.894] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:32.894] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:42:0) after 1m0s
[22:04:33.055] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:42:0)
[22:04:33.055] Timer tick, asking Buildbucket for the build status
[22:04:33.923] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:33.923] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:43:0) after 1m0s
[22:05:34.025] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:43:0)
[22:05:34.025] Timer tick, asking Buildbucket for the build status
[22:05:37.865] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:37.865] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:44:0) after 1m0s
[22:06:37.897] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:44:0)
[22:06:37.897] Timer tick, asking Buildbucket for the build status
[22:06:38.163] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:38.163] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:45:0) after 1m0s
[22:07:38.246] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:45:0)
[22:07:38.246] Timer tick, asking Buildbucket for the build status
[22:07:39.627] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:39.627] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:46:0) after 1m0s
[22:08:39.723] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:46:0)
[22:08:39.723] Timer tick, asking Buildbucket for the build status
[22:08:40.001] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:40.001] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:47:0) after 1m0s
[22:09:40.045] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:47:0)
[22:09:40.045] Timer tick, asking Buildbucket for the build status
[22:09:41.119] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:41.119] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:48:0) after 1m0s
[22:10:41.104] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:48:0)
[22:10:41.104] Timer tick, asking Buildbucket for the build status
[22:10:41.528] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:41.528] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:49:0) after 1m0s
[22:11:41.555] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:49:0)
[22:11:41.555] Timer tick, asking Buildbucket for the build status
[22:11:41.740] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:41.740] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:50:0) after 1m0s
[22:12:41.744] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:50:0)
[22:12:41.744] Timer tick, asking Buildbucket for the build status
[22:12:41.896] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:41.896] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:51:0) after 1m0s
[22:13:41.893] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:51:0)
[22:13:41.893] Timer tick, asking Buildbucket for the build status
[22:13:42.395] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:42.395] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:52:0) after 1m0s
[22:14:42.439] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:52:0)
[22:14:42.439] Timer tick, asking Buildbucket for the build status
[22:14:42.868] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:42.868] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:53:0) after 1m0s
[22:15:42.919] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:53:0)
[22:15:42.919] Timer tick, asking Buildbucket for the build status
[22:15:43.184] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:43.184] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:54:0) after 1m0s
[22:16:43.275] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:54:0)
[22:16:43.275] Timer tick, asking Buildbucket for the build status
[22:16:45.518] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:45.518] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:55:0) after 1m0s
[22:17:45.548] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:55:0)
[22:17:45.548] Timer tick, asking Buildbucket for the build status
[22:17:45.978] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:45.978] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:56:0) after 1m0s
[22:18:46.105] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:56:0)
[22:18:46.105] Timer tick, asking Buildbucket for the build status
[22:18:47.271] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:47.271] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:57:0) after 1m0s
[22:19:47.272] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:57:0)
[22:19:47.272] Timer tick, asking Buildbucket for the build status
[22:19:47.517] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:47.517] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:58:0) after 1m0s
[22:20:47.534] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:58:0)
[22:20:47.534] Timer tick, asking Buildbucket for the build status
[22:20:48.343] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:48.343] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:59:0) after 1m0s
[22:21:48.437] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:59:0)
[22:21:48.437] Timer tick, asking Buildbucket for the build status
[22:21:48.982] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:48.982] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:60:0) after 1m0s
[22:22:49.054] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:60:0)
[22:22:49.054] Timer tick, asking Buildbucket for the build status
[22:22:49.333] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:49.333] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:61:0) after 1m0s
[22:23:49.368] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:61:0)
[22:23:49.368] Timer tick, asking Buildbucket for the build status
[22:23:49.631] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:49.631] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:62:0) after 1m0s
[22:24:49.679] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:62:0)
[22:24:49.679] Timer tick, asking Buildbucket for the build status
[22:24:50.890] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:50.890] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:63:0) after 1m0s
[22:25:50.891] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:63:0)
[22:25:50.891] Timer tick, asking Buildbucket for the build status
[22:25:51.316] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:51.316] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:64:0) after 1m0s
[22:26:51.385] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:64:0)
[22:26:51.385] Timer tick, asking Buildbucket for the build status
[22:26:52.017] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:52.017] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:65:0) after 1m0s
[22:27:52.128] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:65:0)
[22:27:52.128] Timer tick, asking Buildbucket for the build status
[22:27:52.689] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:52.689] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:66:0) after 1m0s
[22:28:52.832] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:66:0)
[22:28:52.832] Timer tick, asking Buildbucket for the build status
[22:28:55.191] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:55.191] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:67:0) after 1m0s
[22:29:55.267] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:67:0)
[22:29:55.267] Timer tick, asking Buildbucket for the build status
[22:29:55.686] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:55.686] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:68:0) after 1m0s
[22:30:55.721] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:68:0)
[22:30:55.721] Timer tick, asking Buildbucket for the build status
[22:30:56.566] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:56.567] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:69:0) after 1m0s
[22:31:56.606] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:69:0)
[22:31:56.606] Timer tick, asking Buildbucket for the build status
[22:31:57.308] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:57.308] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:70:0) after 1m0s
[22:32:57.407] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:70:0)
[22:32:57.407] Timer tick, asking Buildbucket for the build status
[22:32:58.974] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:58.974] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:71:0) after 1m0s
[22:33:59.006] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:71:0)
[22:33:59.006] Timer tick, asking Buildbucket for the build status
[22:33:59.214] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:59.214] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:72:0) after 1m0s
[22:34:59.329] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:72:0)
[22:34:59.329] Timer tick, asking Buildbucket for the build status
[22:34:59.578] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:59.578] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:73:0) after 1m0s
[22:35:59.633] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:73:0)
[22:35:59.633] Timer tick, asking Buildbucket for the build status
[22:36:00.363] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:00.363] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:74:0) after 1m0s
[22:37:00.596] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:74:0)
[22:37:00.596] Timer tick, asking Buildbucket for the build status
[22:37:00.739] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:00.739] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:75:0) after 1m0s
[22:38:00.929] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:75:0)
[22:38:00.929] Timer tick, asking Buildbucket for the build status
[22:38:01.093] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:01.093] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:76:0) after 1m0s
[22:39:01.389] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:76:0)
[22:39:01.389] Timer tick, asking Buildbucket for the build status
[22:39:01.661] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:01.661] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:77:0) after 1m0s
[22:40:01.794] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:77:0)
[22:40:01.794] Timer tick, asking Buildbucket for the build status
[22:40:02.243] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:02.243] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:78:0) after 1m0s
[22:41:02.333] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:78:0)
[22:41:02.333] Timer tick, asking Buildbucket for the build status
[22:41:06.569] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:06.569] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:79:0) after 1m0s
[22:42:06.626] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:79:0)
[22:42:06.626] Timer tick, asking Buildbucket for the build status
[22:42:07.549] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:07.549] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:80:0) after 1m0s
[22:43:07.585] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:80:0)
[22:43:07.585] Timer tick, asking Buildbucket for the build status
[22:43:09.850] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:09.850] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:81:0) after 1m0s
[22:44:09.945] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:81:0)
[22:44:09.945] Timer tick, asking Buildbucket for the build status
[22:44:10.113] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:10.113] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:82:0) after 1m0s
[22:45:10.154] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:82:0)
[22:45:10.154] Timer tick, asking Buildbucket for the build status
[22:45:10.856] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:10.856] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:83:0) after 1m0s
[22:46:10.857] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:83:0)
[22:46:10.857] Timer tick, asking Buildbucket for the build status
[22:46:11.100] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:11.100] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:84:0) after 1m0s
[22:47:11.140] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:84:0)
[22:47:11.140] Timer tick, asking Buildbucket for the build status
[22:47:11.367] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:11.367] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:85:0) after 1m0s
[22:48:11.426] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:85:0)
[22:48:11.426] Timer tick, asking Buildbucket for the build status
[22:48:12.337] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:12.337] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:86:0) after 1m0s
[22:49:12.443] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:86:0)
[22:49:12.443] Timer tick, asking Buildbucket for the build status
[22:49:12.710] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:12.710] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:87:0) after 1m0s
[22:50:12.858] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:87:0)
[22:50:12.858] Timer tick, asking Buildbucket for the build status
[22:50:13.062] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:13.062] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:88:0) after 1m0s
[22:51:13.177] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:88:0)
[22:51:13.177] Timer tick, asking Buildbucket for the build status
[22:51:13.689] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:13.689] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:89:0) after 1m0s
[22:52:13.771] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:89:0)
[22:52:13.771] Timer tick, asking Buildbucket for the build status
[22:52:14.355] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:14.355] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:90:0) after 1m0s
[22:53:14.380] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:90:0)
[22:53:14.380] Timer tick, asking Buildbucket for the build status
[22:53:14.825] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:14.825] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:91:0) after 1m0s
[22:54:14.901] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:91:0)
[22:54:14.901] Timer tick, asking Buildbucket for the build status
[22:54:15.470] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:15.470] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:92:0) after 1m0s
[22:55:15.496] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:92:0)
[22:55:15.496] Timer tick, asking Buildbucket for the build status
[22:55:16.161] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:16.161] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:93:0) after 1m0s
[22:56:16.253] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:93:0)
[22:56:16.253] Timer tick, asking Buildbucket for the build status
[22:56:17.930] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:17.930] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:94:0) after 1m0s
[22:57:17.974] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:94:0)
[22:57:17.974] Timer tick, asking Buildbucket for the build status
[22:57:18.205] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:18.205] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:95:0) after 1m0s
[22:58:18.255] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:95:0)
[22:58:18.255] Timer tick, asking Buildbucket for the build status
[22:58:18.428] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:18.428] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:96:0) after 1m0s
[22:59:18.496] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:96:0)
[22:59:18.496] Timer tick, asking Buildbucket for the build status
[22:59:22.343] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:22.343] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:97:0) after 1m0s
[23:00:22.434] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:97:0)
[23:00:22.434] Timer tick, asking Buildbucket for the build status
[23:00:23.693] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:23.694] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:98:0) after 1m0s
[23:01:23.756] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:98:0)
[23:01:23.756] Timer tick, asking Buildbucket for the build status
[23:01:24.815] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:24.817] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:99:0) after 1m0s
[23:02:24.886] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:99:0)
[23:02:24.886] Timer tick, asking Buildbucket for the build status
[23:02:25.418] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:25.418] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:100:0) after 1m0s
[23:03:25.430] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:100:0)
[23:03:25.430] Timer tick, asking Buildbucket for the build status
[23:03:25.792] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:25.792] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:101:0) after 1m0s
[23:04:25.942] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:101:0)
[23:04:25.942] Timer tick, asking Buildbucket for the build status
[23:04:26.411] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:26.411] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:102:0) after 1m0s
[23:05:26.684] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:102:0)
[23:05:26.684] Timer tick, asking Buildbucket for the build status
[23:05:27.294] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:27.294] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:103:0) after 1m0s
[23:06:27.334] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:103:0)
[23:06:27.334] Timer tick, asking Buildbucket for the build status
[23:06:27.540] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:27.540] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:104:0) after 1m0s
[23:07:27.639] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:104:0)
[23:07:27.639] Timer tick, asking Buildbucket for the build status
[23:07:27.814] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:27.814] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:105:0) after 1m0s
[23:08:27.873] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:105:0)
[23:08:27.873] Timer tick, asking Buildbucket for the build status
[23:08:28.827] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:28.827] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:106:0) after 1m0s
[23:09:28.896] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:106:0)
[23:09:28.896] Timer tick, asking Buildbucket for the build status
[23:09:29.331] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:29.331] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:107:0) after 1m0s
[23:10:29.369] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:107:0)
[23:10:29.369] Timer tick, asking Buildbucket for the build status
[23:10:29.715] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:29.716] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:108:0) after 1m0s
[23:11:29.859] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:108:0)
[23:11:29.859] Timer tick, asking Buildbucket for the build status
[23:11:30.959] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:30.959] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:109:0) after 1m0s
[23:12:31.053] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:109:0)
[23:12:31.053] Timer tick, asking Buildbucket for the build status
[23:12:31.710] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:31.710] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:110:0) after 1m0s
[23:13:31.996] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:110:0)
[23:13:31.996] Timer tick, asking Buildbucket for the build status
[23:13:32.459] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:32.459] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:111:0) after 1m0s
[23:14:32.498] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:111:0)
[23:14:32.499] Timer tick, asking Buildbucket for the build status
[23:14:32.722] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:32.722] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:112:0) after 1m0s
[23:15:32.795] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:112:0)
[23:15:32.795] Timer tick, asking Buildbucket for the build status
[23:15:33.019] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:33.019] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:113:0) after 1m0s
[23:16:33.113] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:113:0)
[23:16:33.113] Timer tick, asking Buildbucket for the build status
[23:16:33.578] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:33.578] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:114:0) after 1m0s
[23:17:33.617] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:114:0)
[23:17:33.617] Timer tick, asking Buildbucket for the build status
[23:17:34.419] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:34.419] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:115:0) after 1m0s
[23:18:34.451] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:115:0)
[23:18:34.451] Timer tick, asking Buildbucket for the build status
[23:18:35.186] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:35.186] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:116:0) after 1m0s
[23:19:35.349] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:116:0)
[23:19:35.349] Timer tick, asking Buildbucket for the build status
[23:19:36.349] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:36.349] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:117:0) after 1m0s
[23:20:36.623] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:117:0)
[23:20:36.623] Timer tick, asking Buildbucket for the build status
[23:20:37.024] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:37.024] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:118:0) after 1m0s
[23:21:37.322] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:118:0)
[23:21:37.322] Timer tick, asking Buildbucket for the build status
[23:21:37.614] Build 8904027562306072240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:37.614] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9069457625805492624:119:0) after 1m0s
[23:21:51.302] Received PubSub notification, asking Buildbucket for the build status
[23:21:51.455] Build 8904027562306072240: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[23:21:51.455] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1566861710804090",
"created_by": "project:chromium",
"created_ts": "1566854623463126",
"failure_reason": "BUILD_FAILURE",
"id": "8904027562306072240",
"parameters_json": "{\"builder_name\": \"ToTiOS\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"e0bb634deb88ffd35335cf9e4af0f13de4a0bf31\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Failed ios_web_inttests (iPhone 6s iOS 12.2).\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"build284-m9\", \"branch\": \"refs/heads/master\", \"buildername\": \"ToTiOS\", \"buildnumber\": 9621, \"got_angle_revision\": \"87b106a0944e5bc83bbee8de95a49c4be3de3825\", \"got_dawn_revision\": \"f19c328b5ba5fb3aaf02e50b5fc11dab93b098b7\", \"got_nacl_revision\": \"058a26f57015051953a727add26e7e26348b9558\", \"got_revision\": \"e0bb634deb88ffd35335cf9e4af0f13de4a0bf31\", \"got_revision_cp\": \"refs/heads/master@{#690500}\", \"got_swarming_client_revision\": \"96f125709acfd0b48fc1e5dae7d6ea42291726ac\", \"got_v8_revision\": \"2334bc12561ce4921dad83678b344df2abf1c775\", \"got_v8_revision_cp\": \"refs/heads/7.8.196@{#1}\", \"got_webrtc_revision\": \"4869bd6309bbe2e9e038451e9549f5a9d12e4a38\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#28957}\", \"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\": \"e0bb634deb88ffd35335cf9e4af0f13de4a0bf31\", \"swarm_hashes\": {\"0\": \"5890f1b55a11a9e99297898eb0c10f37a279c001\", \"1\": \"41a639a362f8deacbbcc5648b457e43822ba582c\", \"10\": \"dbfad38692c29c6d79cba8b81101458768458420\", \"11\": \"51e9b6b63cbd0620f3bf940309e675314de3d9f9\", \"12\": \"1cc9a95846dc25cca864d03fab8b29bfac9d20d0\", \"13\": \"0e1ef9fd0aa28e8ff4ccbdf77aa17efc7e0ca73c\", \"14\": \"d2ae1c60eaca0a4c0f7a25aee02117e8e449a104\", \"15\": \"c07115e2f3a90b093a3e457356ab34261673b989\", \"16\": \"4e884095d2f205879f8b67031fb3fcab15b503c2\", \"2\": \"a3786ebb0e5660c6243f68641c117f6611624a6e\", \"3\": \"02b14c5a194c5814ea910aba9869406836c0f3da\", \"4\": \"68fb65f42f89961c4c9085bd050e485849a2f03d\", \"5\": \"1a425a3a4b9dc08f4ea63ad0dd17b4f89ec8936c\", \"6\": \"353ddc423f96d112766c1a702bf9c77c478049ba\", \"7\": \"ae2c0037ed2ca78aac72169056d859a2894d0a3d\", \"8\": \"58b89338616c9702487c417a74083e3ff464c77c\", \"9\": \"f70a6d82c5e5f027abe80c4d707d237c43cccf96\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4a4feefdd7e355061708c01720b06a0ca8c49ffc9968a2ebc674541738676b8f_v2\", \"builder_5372538a354f163a20d332d881c6c5d85e35654ac8a57c760866f12033f8a8a5_v2\", \"builder_d42b89eab403033f5a2a8102ec9c436397350cedb439d5e09b11874a5784adb4_v2\", \"git\", \"goma_v2\", \"vpython\", \"xcode_ios_11m382q\", \"xcode_ios_11m392q\"], \"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\": [\"build284-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.2).\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1566854792564555",
"status": "COMPLETED",
"status_changed_ts": "1566854793458538",
"tags": [
"build_address:luci.chromium.ci/ToTiOS/9621",
"builder:ToTiOS",
"buildset:commit/git/e0bb634deb88ffd35335cf9e4af0f13de4a0bf31",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e0bb634deb88ffd35335cf9e4af0f13de4a0bf31",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9069457625805492624",
"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/8904027562306072240/+/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:46e8a1c183500110",
"user_agent:luci-scheduler"
],
"updated_ts": "1566861710885166",
"url": "https://ci.chromium.org/b/8904027562306072240",
"utcnow_ts": "1566861711442691"
}
}
[23:21:51.455] Invocation finished in 1h58m10.712859097s with status FAILED