[08:44:00.206] New invocation is queued and will start shortly
[08:44:01.477] Starting the invocation (attempt 1)
[08:44:01.514] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:44:01.514] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:44:01.514] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023029176716029232",
"parameters_json": "{\"builder_name\":\"ToTiOS\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"3575c81dc2c3718dd5f41e49b1bf99d71b0468f0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:ToTiOS",
"scheduler_invocation_id:9023029176716029232",
"scheduler_job_id:chromium/ToTiOS",
"user_agent:luci-scheduler",
"buildset:commit/git/3575c81dc2c3718dd5f41e49b1bf99d71b0468f0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3575c81dc2c3718dd5f41e49b1bf99d71b0468f0",
"gitiles_ref:refs/heads/master"
]
}
[08:44:02.222] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1611132241634604",
"id": "8857599114554463232",
"parameters_json": "{\"builder_name\": \"ToTiOS\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3575c81dc2c3718dd5f41e49b1bf99d71b0468f0\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611132242024517",
"tags": [
"build_address:luci.chromium.ci/ToTiOS/17477",
"builder:ToTiOS",
"buildset:commit/git/3575c81dc2c3718dd5f41e49b1bf99d71b0468f0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3575c81dc2c3718dd5f41e49b1bf99d71b0468f0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023029176716029232",
"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/8857599114554463232/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1611132242024630",
"url": "https://ci.chromium.org/b/8857599114554463232",
"utcnow_ts": "1611132242214114"
}
}
[08:44:02.222] Task URL: https://ci.chromium.org/b/8857599114554463232
[08:44:02.222] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:2:0) after 1m0s
[08:45:02.243] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:2:0)
[08:45:02.243] Timer tick, asking Buildbucket for the build status
[08:45:02.374] Build 8857599114554463232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:45:02.374] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:3:0) after 1m0s
[08:46:02.677] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:3:0)
[08:46:02.677] Timer tick, asking Buildbucket for the build status
[08:46:02.765] Build 8857599114554463232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:46:02.765] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:4:0) after 1m0s
[08:47:03.041] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:4:0)
[08:47:03.041] Timer tick, asking Buildbucket for the build status
[08:47:03.368] Build 8857599114554463232: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:47:03.368] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:5:0) after 1m0s
[08:47:43.471] Received PubSub notification, asking Buildbucket for the build status
[08:47:43.542] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:03.406] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:5:0)
[08:48:03.406] Timer tick, asking Buildbucket for the build status
[08:48:03.494] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:03.494] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:7:0) after 1m0s
[08:49:03.544] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:7:0)
[08:49:03.544] Timer tick, asking Buildbucket for the build status
[08:49:04.156] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:04.156] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:8:0) after 1m0s
[08:50:04.221] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:8:0)
[08:50:04.221] Timer tick, asking Buildbucket for the build status
[08:50:04.337] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:04.337] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:9:0) after 1m0s
[08:51:04.419] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:9:0)
[08:51:04.419] Timer tick, asking Buildbucket for the build status
[08:51:04.536] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:04.536] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:10:0) after 1m0s
[08:52:04.575] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:10:0)
[08:52:04.575] Timer tick, asking Buildbucket for the build status
[08:52:04.636] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:04.636] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:11:0) after 1m0s
[08:53:04.657] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:11:0)
[08:53:04.657] Timer tick, asking Buildbucket for the build status
[08:53:04.754] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:04.754] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:12:0) after 1m0s
[08:54:04.752] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:12:0)
[08:54:04.752] Timer tick, asking Buildbucket for the build status
[08:54:05.019] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:05.019] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:13:0) after 1m0s
[08:55:05.071] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:13:0)
[08:55:05.071] Timer tick, asking Buildbucket for the build status
[08:55:05.345] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:05.345] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:14:0) after 1m0s
[08:56:05.373] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:14:0)
[08:56:05.373] Timer tick, asking Buildbucket for the build status
[08:56:05.457] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:05.457] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:15:0) after 1m0s
[08:57:05.493] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:15:0)
[08:57:05.493] Timer tick, asking Buildbucket for the build status
[08:57:05.700] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:05.700] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:16:0) after 1m0s
[08:58:05.731] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:16:0)
[08:58:05.732] Timer tick, asking Buildbucket for the build status
[08:58:06.073] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:06.073] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:17:0) after 1m0s
[08:59:06.143] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:17:0)
[08:59:06.143] Timer tick, asking Buildbucket for the build status
[08:59:06.292] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:06.292] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:18:0) after 1m0s
[09:00:06.346] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:18:0)
[09:00:06.346] Timer tick, asking Buildbucket for the build status
[09:00:06.418] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:06.419] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:19:0) after 1m0s
[09:01:06.447] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:19:0)
[09:01:06.447] Timer tick, asking Buildbucket for the build status
[09:01:06.607] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:06.607] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:20:0) after 1m0s
[09:02:06.653] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:20:0)
[09:02:06.653] Timer tick, asking Buildbucket for the build status
[09:02:06.824] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:06.824] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:21:0) after 1m0s
[09:03:06.895] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:21:0)
[09:03:06.895] Timer tick, asking Buildbucket for the build status
[09:03:06.983] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:06.983] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:22:0) after 1m0s
[09:04:07.008] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:22:0)
[09:04:07.008] Timer tick, asking Buildbucket for the build status
[09:04:07.176] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:07.176] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:23:0) after 1m0s
[09:05:07.294] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:23:0)
[09:05:07.294] Timer tick, asking Buildbucket for the build status
[09:05:07.383] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:07.383] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:24:0) after 1m0s
[09:06:07.423] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:24:0)
[09:06:07.423] Timer tick, asking Buildbucket for the build status
[09:06:07.489] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:07.489] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:25:0) after 1m0s
[09:07:07.589] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:25:0)
[09:07:07.589] Timer tick, asking Buildbucket for the build status
[09:07:07.669] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:07.669] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:26:0) after 1m0s
[09:08:07.777] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:26:0)
[09:08:07.777] Timer tick, asking Buildbucket for the build status
[09:08:07.855] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:07.855] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:27:0) after 1m0s
[09:09:07.900] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:27:0)
[09:09:07.900] Timer tick, asking Buildbucket for the build status
[09:09:08.007] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:08.007] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:28:0) after 1m0s
[09:10:08.035] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:28:0)
[09:10:08.035] Timer tick, asking Buildbucket for the build status
[09:10:08.115] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:08.115] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:29:0) after 1m0s
[09:11:08.175] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:29:0)
[09:11:08.175] Timer tick, asking Buildbucket for the build status
[09:11:08.232] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:08.232] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:30:0) after 1m0s
[09:12:08.243] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:30:0)
[09:12:08.243] Timer tick, asking Buildbucket for the build status
[09:12:08.297] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:08.297] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:31:0) after 1m0s
[09:13:08.410] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:31:0)
[09:13:08.410] Timer tick, asking Buildbucket for the build status
[09:13:08.474] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:08.474] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:32:0) after 1m0s
[09:14:08.556] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:32:0)
[09:14:08.556] Timer tick, asking Buildbucket for the build status
[09:14:08.682] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:08.682] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:33:0) after 1m0s
[09:15:08.758] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:33:0)
[09:15:08.758] Timer tick, asking Buildbucket for the build status
[09:15:08.882] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:08.882] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:34:0) after 1m0s
[09:16:08.992] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:34:0)
[09:16:08.992] Timer tick, asking Buildbucket for the build status
[09:16:09.365] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:09.365] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:35:0) after 1m0s
[09:17:09.441] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:35:0)
[09:17:09.441] Timer tick, asking Buildbucket for the build status
[09:17:09.719] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:09.719] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:36:0) after 1m0s
[09:18:09.836] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:36:0)
[09:18:09.836] Timer tick, asking Buildbucket for the build status
[09:18:09.933] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:09.933] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:37:0) after 1m0s
[09:19:09.965] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:37:0)
[09:19:09.965] Timer tick, asking Buildbucket for the build status
[09:19:10.716] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:10.717] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:38:0) after 1m0s
[09:20:10.776] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:38:0)
[09:20:10.776] Timer tick, asking Buildbucket for the build status
[09:20:11.270] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:11.270] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:39:0) after 1m0s
[09:21:11.295] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:39:0)
[09:21:11.295] Timer tick, asking Buildbucket for the build status
[09:21:11.523] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:11.523] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:40:0) after 1m0s
[09:22:11.532] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:40:0)
[09:22:11.532] Timer tick, asking Buildbucket for the build status
[09:22:11.595] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:11.595] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:41:0) after 1m0s
[09:23:11.692] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:41:0)
[09:23:11.692] Timer tick, asking Buildbucket for the build status
[09:23:11.863] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:11.863] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:42:0) after 1m0s
[09:24:11.968] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:42:0)
[09:24:11.968] Timer tick, asking Buildbucket for the build status
[09:24:12.073] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:12.073] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:43:0) after 1m0s
[09:25:12.095] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:43:0)
[09:25:12.095] Timer tick, asking Buildbucket for the build status
[09:25:12.314] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:12.314] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:44:0) after 1m0s
[09:26:12.381] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:44:0)
[09:26:12.381] Timer tick, asking Buildbucket for the build status
[09:26:12.537] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:12.537] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:45:0) after 1m0s
[09:27:12.626] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:45:0)
[09:27:12.626] Timer tick, asking Buildbucket for the build status
[09:27:12.699] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:12.699] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:46:0) after 1m0s
[09:28:12.839] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:46:0)
[09:28:12.839] Timer tick, asking Buildbucket for the build status
[09:28:12.897] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:12.897] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:47:0) after 1m0s
[09:29:12.955] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:47:0)
[09:29:12.955] Timer tick, asking Buildbucket for the build status
[09:29:13.079] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:13.079] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:48:0) after 1m0s
[09:30:13.193] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:48:0)
[09:30:13.193] Timer tick, asking Buildbucket for the build status
[09:30:13.276] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:13.276] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:49:0) after 1m0s
[09:31:13.377] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:49:0)
[09:31:13.377] Timer tick, asking Buildbucket for the build status
[09:31:13.449] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:13.449] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:50:0) after 1m0s
[09:32:13.462] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:50:0)
[09:32:13.462] Timer tick, asking Buildbucket for the build status
[09:32:13.780] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:13.780] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:51:0) after 1m0s
[09:33:13.811] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:51:0)
[09:33:13.811] Timer tick, asking Buildbucket for the build status
[09:33:13.871] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:13.871] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:52:0) after 1m0s
[09:34:13.912] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:52:0)
[09:34:13.912] Timer tick, asking Buildbucket for the build status
[09:34:14.019] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:14.019] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:53:0) after 1m0s
[09:35:14.016] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:53:0)
[09:35:14.016] Timer tick, asking Buildbucket for the build status
[09:35:14.091] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:14.091] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:54:0) after 1m0s
[09:36:14.161] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:54:0)
[09:36:14.161] Timer tick, asking Buildbucket for the build status
[09:36:14.218] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:14.218] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:55:0) after 1m0s
[09:37:14.232] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:55:0)
[09:37:14.232] Timer tick, asking Buildbucket for the build status
[09:37:14.329] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:14.329] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:56:0) after 1m0s
[09:38:14.484] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:56:0)
[09:38:14.484] Timer tick, asking Buildbucket for the build status
[09:38:14.921] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:14.921] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:57:0) after 1m0s
[09:39:15.045] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:57:0)
[09:39:15.045] Timer tick, asking Buildbucket for the build status
[09:39:15.327] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:15.327] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:58:0) after 1m0s
[09:40:15.364] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:58:0)
[09:40:15.364] Timer tick, asking Buildbucket for the build status
[09:40:15.534] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:15.534] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:59:0) after 1m0s
[09:41:15.577] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:59:0)
[09:41:15.577] Timer tick, asking Buildbucket for the build status
[09:41:15.908] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:15.908] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:60:0) after 1m0s
[09:42:15.933] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:60:0)
[09:42:15.933] Timer tick, asking Buildbucket for the build status
[09:42:16.334] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:16.334] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:61:0) after 1m0s
[09:43:16.367] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:61:0)
[09:43:16.367] Timer tick, asking Buildbucket for the build status
[09:43:16.500] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:16.500] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:62:0) after 1m0s
[09:44:16.503] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:62:0)
[09:44:16.503] Timer tick, asking Buildbucket for the build status
[09:44:16.616] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:16.616] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:63:0) after 1m0s
[09:45:16.670] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:63:0)
[09:45:16.670] Timer tick, asking Buildbucket for the build status
[09:45:16.922] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:16.922] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:64:0) after 1m0s
[09:46:16.976] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:64:0)
[09:46:16.976] Timer tick, asking Buildbucket for the build status
[09:46:17.037] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:17.037] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:65:0) after 1m0s
[09:47:17.072] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:65:0)
[09:47:17.072] Timer tick, asking Buildbucket for the build status
[09:47:17.165] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:17.165] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:66:0) after 1m0s
[09:48:17.287] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:66:0)
[09:48:17.287] Timer tick, asking Buildbucket for the build status
[09:48:17.474] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:17.474] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:67:0) after 1m0s
[09:49:17.513] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:67:0)
[09:49:17.513] Timer tick, asking Buildbucket for the build status
[09:49:17.811] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:17.811] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:68:0) after 1m0s
[09:50:18.064] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:68:0)
[09:50:18.064] Timer tick, asking Buildbucket for the build status
[09:50:18.345] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:18.345] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:69:0) after 1m0s
[09:51:18.469] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:69:0)
[09:51:18.469] Timer tick, asking Buildbucket for the build status
[09:51:18.705] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:18.705] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:70:0) after 1m0s
[09:52:18.773] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:70:0)
[09:52:18.773] Timer tick, asking Buildbucket for the build status
[09:52:18.960] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:18.960] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:71:0) after 1m0s
[09:53:19.174] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:71:0)
[09:53:19.175] Timer tick, asking Buildbucket for the build status
[09:53:19.244] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:19.244] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:72:0) after 1m0s
[09:54:19.293] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:72:0)
[09:54:19.293] Timer tick, asking Buildbucket for the build status
[09:54:19.403] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:19.403] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:73:0) after 1m0s
[09:55:19.426] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:73:0)
[09:55:19.426] Timer tick, asking Buildbucket for the build status
[09:55:19.493] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:19.493] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:74:0) after 1m0s
[09:56:19.627] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:74:0)
[09:56:19.627] Timer tick, asking Buildbucket for the build status
[09:56:19.724] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:19.724] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:75:0) after 1m0s
[09:57:19.762] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:75:0)
[09:57:19.762] Timer tick, asking Buildbucket for the build status
[09:57:19.971] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:19.971] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:76:0) after 1m0s
[09:58:20.004] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:76:0)
[09:58:20.004] Timer tick, asking Buildbucket for the build status
[09:58:20.102] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:20.102] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:77:0) after 1m0s
[09:59:20.158] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:77:0)
[09:59:20.158] Timer tick, asking Buildbucket for the build status
[09:59:20.501] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:20.501] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:78:0) after 1m0s
[10:00:20.579] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:78:0)
[10:00:20.579] Timer tick, asking Buildbucket for the build status
[10:00:20.794] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:20.794] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:79:0) after 1m0s
[10:01:20.828] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:79:0)
[10:01:20.828] Timer tick, asking Buildbucket for the build status
[10:01:20.893] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:20.893] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:80:0) after 1m0s
[10:02:21.041] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:80:0)
[10:02:21.041] Timer tick, asking Buildbucket for the build status
[10:02:21.124] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:21.124] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:81:0) after 1m0s
[10:03:21.230] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:81:0)
[10:03:21.230] Timer tick, asking Buildbucket for the build status
[10:03:21.390] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:21.390] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:82:0) after 1m0s
[10:04:21.407] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:82:0)
[10:04:21.407] Timer tick, asking Buildbucket for the build status
[10:04:21.498] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:21.498] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:83:0) after 1m0s
[10:05:21.553] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:83:0)
[10:05:21.553] Timer tick, asking Buildbucket for the build status
[10:05:21.620] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:21.620] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:84:0) after 1m0s
[10:06:21.631] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:84:0)
[10:06:21.631] Timer tick, asking Buildbucket for the build status
[10:06:21.686] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:21.686] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:85:0) after 1m0s
[10:07:21.746] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:85:0)
[10:07:21.746] Timer tick, asking Buildbucket for the build status
[10:07:21.823] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:21.823] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:86:0) after 1m0s
[10:08:21.845] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:86:0)
[10:08:21.845] Timer tick, asking Buildbucket for the build status
[10:08:21.934] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:21.934] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:87:0) after 1m0s
[10:09:22.029] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:87:0)
[10:09:22.029] Timer tick, asking Buildbucket for the build status
[10:09:22.333] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:22.333] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:88:0) after 1m0s
[10:10:22.327] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:88:0)
[10:10:22.327] Timer tick, asking Buildbucket for the build status
[10:10:22.497] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:22.497] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:89:0) after 1m0s
[10:11:22.602] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:89:0)
[10:11:22.602] Timer tick, asking Buildbucket for the build status
[10:11:22.675] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:22.675] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:90:0) after 1m0s
[10:12:22.701] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:90:0)
[10:12:22.701] Timer tick, asking Buildbucket for the build status
[10:12:22.916] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:22.916] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:91:0) after 1m0s
[10:13:22.976] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:91:0)
[10:13:22.976] Timer tick, asking Buildbucket for the build status
[10:13:23.043] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:23.043] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:92:0) after 1m0s
[10:14:23.066] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:92:0)
[10:14:23.066] Timer tick, asking Buildbucket for the build status
[10:14:23.160] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:23.160] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:93:0) after 1m0s
[10:15:23.198] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:93:0)
[10:15:23.198] Timer tick, asking Buildbucket for the build status
[10:15:23.589] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:23.589] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:94:0) after 1m0s
[10:16:23.713] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:94:0)
[10:16:23.713] Timer tick, asking Buildbucket for the build status
[10:16:23.769] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:23.769] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:95:0) after 1m0s
[10:17:28.356] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:95:0)
[10:17:28.356] Timer tick, asking Buildbucket for the build status
[10:17:28.537] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:28.537] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:96:0) after 1m0s
[10:18:28.579] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:96:0)
[10:18:28.579] Timer tick, asking Buildbucket for the build status
[10:18:28.650] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:28.650] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:97:0) after 1m0s
[10:19:28.675] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:97:0)
[10:19:28.675] Timer tick, asking Buildbucket for the build status
[10:19:28.770] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:28.770] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:98:0) after 1m0s
[10:20:28.841] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:98:0)
[10:20:28.841] Timer tick, asking Buildbucket for the build status
[10:20:29.052] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:29.052] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:99:0) after 1m0s
[10:21:29.109] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:99:0)
[10:21:29.109] Timer tick, asking Buildbucket for the build status
[10:21:29.173] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:29.173] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:100:0) after 1m0s
[10:22:29.248] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:100:0)
[10:22:29.248] Timer tick, asking Buildbucket for the build status
[10:22:29.722] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:29.722] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:101:0) after 1m0s
[10:23:29.842] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:101:0)
[10:23:29.842] Timer tick, asking Buildbucket for the build status
[10:23:29.895] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:29.895] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:102:0) after 1m0s
[10:24:30.028] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:102:0)
[10:24:30.028] Timer tick, asking Buildbucket for the build status
[10:24:30.361] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:30.361] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:103:0) after 1m0s
[10:25:30.357] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:103:0)
[10:25:30.357] Timer tick, asking Buildbucket for the build status
[10:25:30.553] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:30.553] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:104:0) after 1m0s
[10:26:30.611] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:104:0)
[10:26:30.611] Timer tick, asking Buildbucket for the build status
[10:26:30.668] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:30.668] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:105:0) after 1m0s
[10:27:30.743] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:105:0)
[10:27:30.743] Timer tick, asking Buildbucket for the build status
[10:27:30.813] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:30.813] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:106:0) after 1m0s
[10:28:30.895] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:106:0)
[10:28:30.895] Timer tick, asking Buildbucket for the build status
[10:28:31.274] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:31.274] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:107:0) after 1m0s
[10:29:31.347] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:107:0)
[10:29:31.347] Timer tick, asking Buildbucket for the build status
[10:29:31.449] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:31.449] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:108:0) after 1m0s
[10:30:31.541] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:108:0)
[10:30:31.541] Timer tick, asking Buildbucket for the build status
[10:30:31.668] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:31.668] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:109:0) after 1m0s
[10:31:31.703] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:109:0)
[10:31:31.703] Timer tick, asking Buildbucket for the build status
[10:31:31.906] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:31.906] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:110:0) after 1m0s
[10:32:31.946] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:110:0)
[10:32:31.946] Timer tick, asking Buildbucket for the build status
[10:32:32.015] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:32.015] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:111:0) after 1m0s
[10:33:31.996] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:111:0)
[10:33:31.996] Timer tick, asking Buildbucket for the build status
[10:33:32.079] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:32.079] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:112:0) after 1m0s
[10:34:32.120] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:112:0)
[10:34:32.120] Timer tick, asking Buildbucket for the build status
[10:34:32.226] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:32.226] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:113:0) after 1m0s
[10:35:32.322] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:113:0)
[10:35:32.322] Timer tick, asking Buildbucket for the build status
[10:35:32.509] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:32.509] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:114:0) after 1m0s
[10:36:32.606] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:114:0)
[10:36:32.606] Timer tick, asking Buildbucket for the build status
[10:36:32.665] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:32.665] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:115:0) after 1m0s
[10:37:32.694] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:115:0)
[10:37:32.694] Timer tick, asking Buildbucket for the build status
[10:37:32.757] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:32.757] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:116:0) after 1m0s
[10:38:32.783] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:116:0)
[10:38:32.783] Timer tick, asking Buildbucket for the build status
[10:38:32.841] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:32.841] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:117:0) after 1m0s
[10:39:32.978] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:117:0)
[10:39:32.979] Timer tick, asking Buildbucket for the build status
[10:39:33.068] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:33.068] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:118:0) after 1m0s
[10:40:33.134] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:118:0)
[10:40:33.134] Timer tick, asking Buildbucket for the build status
[10:40:33.321] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:33.321] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:119:0) after 1m0s
[10:41:33.457] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:119:0)
[10:41:33.457] Timer tick, asking Buildbucket for the build status
[10:41:33.526] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:33.526] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:120:0) after 1m0s
[10:42:33.601] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:120:0)
[10:42:33.601] Timer tick, asking Buildbucket for the build status
[10:42:33.773] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:33.773] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:121:0) after 1m0s
[10:43:33.846] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:121:0)
[10:43:33.846] Timer tick, asking Buildbucket for the build status
[10:43:33.914] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:33.914] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:122:0) after 1m0s
[10:44:34.014] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:122:0)
[10:44:34.014] Timer tick, asking Buildbucket for the build status
[10:44:34.136] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:34.136] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:123:0) after 1m0s
[10:45:34.235] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:123:0)
[10:45:34.235] Timer tick, asking Buildbucket for the build status
[10:45:34.302] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:34.302] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:124:0) after 1m0s
[10:46:34.356] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:124:0)
[10:46:34.356] Timer tick, asking Buildbucket for the build status
[10:46:34.414] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:34.414] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:125:0) after 1m0s
[10:47:34.501] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:125:0)
[10:47:34.501] Timer tick, asking Buildbucket for the build status
[10:47:34.632] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:34.632] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:126:0) after 1m0s
[10:48:34.796] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:126:0)
[10:48:34.796] Timer tick, asking Buildbucket for the build status
[10:48:34.859] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:34.859] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:127:0) after 1m0s
[10:49:35.156] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:127:0)
[10:49:35.156] Timer tick, asking Buildbucket for the build status
[10:49:35.689] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:35.689] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:128:0) after 1m0s
[10:50:35.719] Handling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:128:0)
[10:50:35.719] Timer tick, asking Buildbucket for the build status
[10:50:35.836] Build 8857599114554463232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:35.836] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOS:9023029176716029232:129:0) after 1m0s
[10:51:09.279] Received PubSub notification, asking Buildbucket for the build status
[10:51:09.345] Build 8857599114554463232: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[10:51:09.345] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1611139868025304",
"created_by": "project:chromium",
"created_ts": "1611132241634604",
"failure_reason": "BUILD_FAILURE",
"id": "8857599114554463232",
"parameters_json": "{\"builder_name\": \"ToTiOS\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3575c81dc2c3718dd5f41e49b1bf99d71b0468f0\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"#### Step _compile_ failed. Error logs are shown below:\\n```\\n[13892/34790] CXX obj/remoting/base/unit_tests/protobuf_http_client_unittest.o\\nFAILED: obj/remoting/base/unit_tests/protobuf_http_client_unittest.o\\n../../third_party/llvm-build/Release+Asserts/bin/clang++ -MMD -MF obj/remoting/base/unit_tests/proto...(too long)\\nDependent types cannot show up in debug information\\nUNREACHABLE executed at /opt/s/w/ir/cache/builder/src/third_party/llvm/clang/lib/CodeGen/CGDebugInfo.cpp:3235!\\nPLEASE submit a bug report to https://crbug.com and run tools/clang/scripts/process_crashreports.py (only works inside Google) which will upload a report and include the crash backtrace, preprocessed source, and associated run script.\\nStack dump:\\n```\\n##### ...The message was too long...\\n#### More information in raw_io.output[failure_summary]\"}, \"properties\": {\"$build/goma\": {\"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"3575c81dc2c3718dd5f41e49b1bf99d71b0468f0\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/opt/s/w/ir/cache\", \"temp_dir\": \"/opt/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"build717-m9\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.clang\", \"buildername\": \"ToTiOS\", \"buildnumber\": 17477, \"clang_revision\": \"llvmorg-12-init-16548-gf1d5cbbd-1\", \"got_angle_revision\": \"192a0147c3fe465650b3a3ef45ea61c02b2de5a1\", \"got_dawn_revision\": \"4b316994ad01ab6b87032c5fd9b53c6d18956d8d\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"3575c81dc2c3718dd5f41e49b1bf99d71b0468f0\", \"got_revision_cp\": \"refs/heads/master@{#845103}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"89fd1480b47e8c8668dfd3c24302c53b76ba4c88\", \"got_v8_revision\": \"69c461e9bd40c5c45d553d3d19bb2771e32b4d64\", \"got_v8_revision_cp\": \"refs/heads/9.0.18@{#1}\", \"got_webrtc_revision\": \"5eb527cf7fb28e22d0687c0295cb9f1bb458160c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33034}\", \"path_config\": \"generic\", \"perf_dashboard_machine_group\": \"ChromiumClang\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3575c81dc2c3718dd5f41e49b1bf99d71b0468f0\", \"xcode_build_version\": \"12a7209\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"ToTiOS\", \"ToTiOSDevice\"], \"caches\": [\"builder_4a4feefdd7e355061708c01720b06a0ca8c49ffc9968a2ebc674541738676b8f_v2\", \"git\", \"goma_v2\", \"vpython\", \"xcode_ios_12a7209\"], \"cores\": [\"12\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-i7-8700B\"], \"gce\": [\"0\"], \"gpu\": [\"8086\", \"8086:3e9b\"], \"hidpi\": [\"0\"], \"id\": [\"build717-m9\"], \"mac_model\": [\"Macmini8,1\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Mac\", \"Mac-10\", \"Mac-10.15\", \"Mac-10.15.6\", \"Mac-10.15.6-19G2021\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.16\"], \"server_version\": [\"5666-679c9bd\"], \"ssd\": [\"1\"], \"zone\": [\"us\", \"us-atl\", \"us-atl-golo\", \"us-atl-golo-m9\"]}}, \"ui\": {\"info\": \"#### Step _compile_ failed. Error logs are shown below:\\n```\\n[13892/34790] CXX obj/remoting/base/unit_tests/protobuf_http_client_unittest.o\\nFAILED: obj/remoting/base/unit_tests/protobuf_http_client_unittest.o\\n../../third_party/llvm-build/Release+Asserts/bin/clang++ -MMD -MF obj/remoting/base/unit_tests/proto...(too long)\\nDependent types cannot show up in debug information\\nUNREACHABLE executed at /opt/s/w/ir/cache/builder/src/third_party/llvm/clang/lib/CodeGen/CGDebugInfo.cpp:3235!\\nPLEASE submit a bug report to https://crbug.com and run tools/clang/scripts/process_crashreports.py (only works inside Google) which will upload a report and include the crash backtrace, preprocessed source, and associated run script.\\nStack dump:\\n```\\n##### ...The message was too long...\\n#### More information in raw_io.output[failure_summary]\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611132462555626",
"status": "COMPLETED",
"status_changed_ts": "1611132463041521",
"tags": [
"build_address:luci.chromium.ci/ToTiOS/17477",
"builder:ToTiOS",
"buildset:commit/git/3575c81dc2c3718dd5f41e49b1bf99d71b0468f0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3575c81dc2c3718dd5f41e49b1bf99d71b0468f0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023029176716029232",
"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/8857599114554463232/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Mac",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:5137c8931ed10d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1611139868602999",
"url": "https://ci.chromium.org/b/8857599114554463232",
"utcnow_ts": "1611139869337068"
}
}
[10:51:09.345] Invocation finished in 2h7m9.156339992s with status FAILED