[13:17:01.241] New invocation is queued and will start shortly
[13:17:02.489] Starting the invocation (attempt 1)
[13:17:02.531] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:17:02.531] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:17:02.531] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9039953632712476528",
"parameters_json": "{\"builder_name\":\"ToTAndroidOfficial\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"ee54f959b14b734d67e5a403dd3ee9cc7d54dd1e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:ToTAndroidOfficial",
"scheduler_invocation_id:9039953632712476528",
"scheduler_job_id:chromium/ToTAndroidOfficial",
"user_agent:luci-scheduler",
"buildset:commit/git/ee54f959b14b734d67e5a403dd3ee9cc7d54dd1e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ee54f959b14b734d67e5a403dd3ee9cc7d54dd1e",
"gitiles_ref:refs/heads/master"
]
}
[13:17:03.921] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1594991822673088",
"id": "8874523570506714048",
"parameters_json": "{\"builder_name\": \"ToTAndroidOfficial\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ee54f959b14b734d67e5a403dd3ee9cc7d54dd1e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1594991823568426",
"tags": [
"build_address:luci.chromium.ci/ToTAndroidOfficial/7666",
"builder:ToTAndroidOfficial",
"buildset:commit/git/ee54f959b14b734d67e5a403dd3ee9cc7d54dd1e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ee54f959b14b734d67e5a403dd3ee9cc7d54dd1e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9039953632712476528",
"scheduler_job_id:chromium/ToTAndroidOfficial",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8874523570506714048/+/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": "1594991823568576",
"url": "https://ci.chromium.org/b/8874523570506714048",
"utcnow_ts": "1594991823891835"
}
}
[13:17:03.921] Task URL: https://ci.chromium.org/b/8874523570506714048
[13:17:03.921] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:2:0) after 1m0s
[13:18:03.971] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:2:0)
[13:18:03.971] Timer tick, asking Buildbucket for the build status
[13:18:04.182] Build 8874523570506714048: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:18:04.182] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:3:0) after 1m0s
[13:19:04.212] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:3:0)
[13:19:04.212] Timer tick, asking Buildbucket for the build status
[13:19:04.442] Build 8874523570506714048: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:19:04.443] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:4:0) after 1m0s
[13:20:04.490] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:4:0)
[13:20:04.490] Timer tick, asking Buildbucket for the build status
[13:20:04.652] Build 8874523570506714048: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:20:04.652] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:5:0) after 1m0s
[13:21:04.719] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:5:0)
[13:21:04.719] Timer tick, asking Buildbucket for the build status
[13:21:05.017] Build 8874523570506714048: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:21:05.017] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:6:0) after 1m0s
[13:22:05.054] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:6:0)
[13:22:05.054] Timer tick, asking Buildbucket for the build status
[13:22:05.187] Build 8874523570506714048: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:22:05.187] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:7:0) after 1m0s
[13:22:24.664] Received PubSub notification, asking Buildbucket for the build status
[13:22:24.958] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:05.246] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:7:0)
[13:23:05.246] Timer tick, asking Buildbucket for the build status
[13:23:05.370] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:05.370] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:9:0) after 1m0s
[13:24:05.397] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:9:0)
[13:24:05.397] Timer tick, asking Buildbucket for the build status
[13:24:05.489] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:05.489] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:10:0) after 1m0s
[13:25:05.527] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:10:0)
[13:25:05.527] Timer tick, asking Buildbucket for the build status
[13:25:05.633] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:05.633] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:11:0) after 1m0s
[13:26:05.709] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:11:0)
[13:26:05.709] Timer tick, asking Buildbucket for the build status
[13:26:05.829] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:05.829] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:12:0) after 1m0s
[13:27:05.875] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:12:0)
[13:27:05.875] Timer tick, asking Buildbucket for the build status
[13:27:06.202] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:06.202] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:13:0) after 1m0s
[13:28:06.245] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:13:0)
[13:28:06.245] Timer tick, asking Buildbucket for the build status
[13:28:06.409] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:06.409] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:14:0) after 1m0s
[13:29:06.552] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:14:0)
[13:29:06.552] Timer tick, asking Buildbucket for the build status
[13:29:06.768] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:06.768] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:15:0) after 1m0s
[13:30:06.803] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:15:0)
[13:30:06.803] Timer tick, asking Buildbucket for the build status
[13:30:07.013] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:07.013] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:16:0) after 1m0s
[13:31:07.182] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:16:0)
[13:31:07.182] Timer tick, asking Buildbucket for the build status
[13:31:07.305] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:07.305] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:17:0) after 1m0s
[13:32:07.284] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:17:0)
[13:32:07.284] Timer tick, asking Buildbucket for the build status
[13:32:07.441] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:07.441] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:18:0) after 1m0s
[13:33:07.522] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:18:0)
[13:33:07.522] Timer tick, asking Buildbucket for the build status
[13:33:07.935] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:07.935] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:19:0) after 1m0s
[13:34:07.991] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:19:0)
[13:34:07.991] Timer tick, asking Buildbucket for the build status
[13:34:08.277] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:08.277] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:20:0) after 1m0s
[13:35:08.308] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:20:0)
[13:35:08.308] Timer tick, asking Buildbucket for the build status
[13:35:08.686] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:08.687] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:21:0) after 1m0s
[13:36:08.927] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:21:0)
[13:36:08.927] Timer tick, asking Buildbucket for the build status
[13:36:09.052] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:09.052] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:22:0) after 1m0s
[13:37:09.180] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:22:0)
[13:37:09.180] Timer tick, asking Buildbucket for the build status
[13:37:09.251] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:09.251] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:23:0) after 1m0s
[13:38:09.400] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:23:0)
[13:38:09.400] Timer tick, asking Buildbucket for the build status
[13:38:09.527] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:09.527] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:24:0) after 1m0s
[13:39:09.642] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:24:0)
[13:39:09.642] Timer tick, asking Buildbucket for the build status
[13:39:09.706] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:09.706] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:25:0) after 1m0s
[13:40:09.730] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:25:0)
[13:40:09.730] Timer tick, asking Buildbucket for the build status
[13:40:10.135] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:10.135] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:26:0) after 1m0s
[13:41:10.282] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:26:0)
[13:41:10.283] Timer tick, asking Buildbucket for the build status
[13:41:10.364] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:10.364] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:27:0) after 1m0s
[13:42:10.516] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:27:0)
[13:42:10.516] Timer tick, asking Buildbucket for the build status
[13:42:10.718] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:10.718] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:28:0) after 1m0s
[13:43:10.835] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:28:0)
[13:43:10.835] Timer tick, asking Buildbucket for the build status
[13:43:10.946] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:10.946] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:29:0) after 1m0s
[13:44:11.014] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:29:0)
[13:44:11.014] Timer tick, asking Buildbucket for the build status
[13:44:11.246] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:11.246] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:30:0) after 1m0s
[13:45:11.346] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:30:0)
[13:45:11.346] Timer tick, asking Buildbucket for the build status
[13:45:11.501] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:11.501] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:31:0) after 1m0s
[13:46:11.582] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:31:0)
[13:46:11.582] Timer tick, asking Buildbucket for the build status
[13:46:11.655] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:11.655] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:32:0) after 1m0s
[13:47:11.856] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:32:0)
[13:47:11.856] Timer tick, asking Buildbucket for the build status
[13:47:12.124] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:12.124] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:33:0) after 1m0s
[13:48:12.142] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:33:0)
[13:48:12.142] Timer tick, asking Buildbucket for the build status
[13:48:12.285] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:12.285] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:34:0) after 1m0s
[13:49:12.397] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:34:0)
[13:49:12.397] Timer tick, asking Buildbucket for the build status
[13:49:12.535] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:12.535] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:35:0) after 1m0s
[13:50:12.655] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:35:0)
[13:50:12.655] Timer tick, asking Buildbucket for the build status
[13:50:13.012] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:13.012] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:36:0) after 1m0s
[13:51:13.054] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:36:0)
[13:51:13.054] Timer tick, asking Buildbucket for the build status
[13:51:13.387] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:13.387] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:37:0) after 1m0s
[13:52:13.411] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:37:0)
[13:52:13.411] Timer tick, asking Buildbucket for the build status
[13:52:13.729] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:13.729] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:38:0) after 1m0s
[13:53:13.854] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:38:0)
[13:53:13.855] Timer tick, asking Buildbucket for the build status
[13:53:14.013] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:14.013] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:39:0) after 1m0s
[13:54:14.055] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:39:0)
[13:54:14.055] Timer tick, asking Buildbucket for the build status
[13:54:14.173] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:14.173] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:40:0) after 1m0s
[13:55:14.263] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:40:0)
[13:55:14.263] Timer tick, asking Buildbucket for the build status
[13:55:14.358] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:14.358] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:41:0) after 1m0s
[13:56:14.425] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:41:0)
[13:56:14.425] Timer tick, asking Buildbucket for the build status
[13:56:14.609] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:14.609] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:42:0) after 1m0s
[13:57:14.658] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:42:0)
[13:57:14.658] Timer tick, asking Buildbucket for the build status
[13:57:14.860] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:14.860] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:43:0) after 1m0s
[13:58:14.943] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:43:0)
[13:58:14.943] Timer tick, asking Buildbucket for the build status
[13:58:15.078] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:15.078] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:44:0) after 1m0s
[13:59:15.162] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:44:0)
[13:59:15.162] Timer tick, asking Buildbucket for the build status
[13:59:15.324] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:15.324] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:45:0) after 1m0s
[14:00:15.362] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:45:0)
[14:00:15.362] Timer tick, asking Buildbucket for the build status
[14:00:15.714] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:15.714] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:46:0) after 1m0s
[14:01:15.743] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:46:0)
[14:01:15.743] Timer tick, asking Buildbucket for the build status
[14:01:15.916] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:15.916] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:47:0) after 1m0s
[14:02:15.968] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:47:0)
[14:02:15.968] Timer tick, asking Buildbucket for the build status
[14:02:16.108] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:16.108] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:48:0) after 1m0s
[14:03:16.106] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:48:0)
[14:03:16.106] Timer tick, asking Buildbucket for the build status
[14:03:16.225] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:16.226] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:49:0) after 1m0s
[14:04:16.291] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:49:0)
[14:04:16.291] Timer tick, asking Buildbucket for the build status
[14:04:16.439] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:16.439] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:50:0) after 1m0s
[14:05:16.466] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:50:0)
[14:05:16.466] Timer tick, asking Buildbucket for the build status
[14:05:17.554] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:17.554] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:51:0) after 1m0s
[14:06:17.583] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:51:0)
[14:06:17.583] Timer tick, asking Buildbucket for the build status
[14:06:17.761] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:17.762] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:52:0) after 1m0s
[14:07:17.815] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:52:0)
[14:07:17.815] Timer tick, asking Buildbucket for the build status
[14:07:17.875] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:17.875] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:53:0) after 1m0s
[14:08:17.897] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:53:0)
[14:08:17.897] Timer tick, asking Buildbucket for the build status
[14:08:18.192] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:18.192] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:54:0) after 1m0s
[14:09:18.266] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:54:0)
[14:09:18.266] Timer tick, asking Buildbucket for the build status
[14:09:18.381] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:18.381] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:55:0) after 1m0s
[14:10:18.454] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:55:0)
[14:10:18.455] Timer tick, asking Buildbucket for the build status
[14:10:19.323] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:19.323] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:56:0) after 1m0s
[14:11:19.345] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:56:0)
[14:11:19.345] Timer tick, asking Buildbucket for the build status
[14:11:19.476] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:19.476] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:57:0) after 1m0s
[14:12:19.499] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:57:0)
[14:12:19.499] Timer tick, asking Buildbucket for the build status
[14:12:19.560] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:19.560] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:58:0) after 1m0s
[14:13:19.740] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:58:0)
[14:13:19.740] Timer tick, asking Buildbucket for the build status
[14:13:19.884] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:19.884] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:59:0) after 1m0s
[14:14:19.958] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:59:0)
[14:14:19.958] Timer tick, asking Buildbucket for the build status
[14:14:20.048] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:20.048] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:60:0) after 1m0s
[14:15:20.116] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:60:0)
[14:15:20.116] Timer tick, asking Buildbucket for the build status
[14:15:20.312] Build 8874523570506714048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:20.312] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidOfficial:9039953632712476528:61:0) after 1m0s
[14:15:42.484] Received PubSub notification, asking Buildbucket for the build status
[14:15:42.545] Build 8874523570506714048: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[14:15:42.545] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1594995342042628",
"created_by": "project:chromium",
"created_ts": "1594991822673088",
"failure_reason": "BUILD_FAILURE",
"id": "8874523570506714048",
"parameters_json": "{\"builder_name\": \"ToTAndroidOfficial\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ee54f959b14b734d67e5a403dd3ee9cc7d54dd1e\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"#### Step _compile_ failed. Error logs are shown below:\\n```\\n[17040/140419] LINK ./opus_demo\\nFAILED: opus_demo exe.unstripped/opus_demo exe.unstripped/opus_demo.map.gz\\n/b/s/w/ir/cipd_bin_packages/cpython/bin/python \\\"../../build/toolchain/gcc_link_wrapper.py\\\" --output=...(too long)\\nld.lld: /b/s/w/ir/cache/builder/src/third_party/llvm/llvm/lib/Transforms/Vectorize/LoopVectorize.cpp:2817: void llvm::InnerLoopVectorizer::emitMemRuntimeChecks(llvm::Loop*, llvm::BasicBlock*): Assertion `Cost-\u003eHints-\u003egetForce() == LoopVectorizeHints::FK_Enabled \u0026\u0026 \\\"Cannot emit memory checks when optimizing for size, unless forced \\\" \\\"to vectorize.\\\"' failed.\\n```\\n##### ...The message was too long...\\n#### More information in raw_io.output[failure_summary]\"}, \"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\": \"luci-chromium-ci-xenial-us-central1-b-32-7-845s\", \"branch\": \"refs/heads/master\", \"buildername\": \"ToTAndroidOfficial\", \"buildnumber\": 7666, \"clang_revision\": \"n360612-9275e143-0\", \"got_angle_revision\": \"cc5513855cc5bed41612c5cadd60f665e24ccce5\", \"got_dawn_revision\": \"450b6f4f844dab9417e54f08adf5b9d2ad30d1d7\", \"got_nacl_revision\": \"fd20fafa8ec19700e12daa031bf792bae8b6bb21\", \"got_revision\": \"ee54f959b14b734d67e5a403dd3ee9cc7d54dd1e\", \"got_revision_cp\": \"refs/heads/master@{#789457}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"e8dd233c7a85f3c689caf06c226a7f8405a480d3\", \"got_v8_revision\": \"72a5712edeff2f50384d4f57b2267ab41ed8b6f7\", \"got_v8_revision_cp\": \"refs/heads/8.6.163@{#1}\", \"got_webrtc_revision\": \"1a09faed62a4b28140ebd7404345785b52c1aa7b\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31753}\", \"mastername\": \"chromium.clang\", \"path_config\": \"generic\", \"perf_dashboard_machine_group\": \"ChromiumClang\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ee54f959b14b734d67e5a403dd3ee9cc7d54dd1e\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0264569d20b29e1d5e6df044c8d1223ed84f9f22180c83dac41e8db0ff2b71db_v2\", \"builder_39819929c28edbb94cfd890831720319bcf41d58e7ee63cb8a54e18e1fdfd830_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-32-7-845s\"], \"image\": [\"chrome-xenial-20052400-59eb8623c93\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5409-9d77717\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"#### Step _compile_ failed. Error logs are shown below:\\n```\\n[17040/140419] LINK ./opus_demo\\nFAILED: opus_demo exe.unstripped/opus_demo exe.unstripped/opus_demo.map.gz\\n/b/s/w/ir/cipd_bin_packages/cpython/bin/python \\\"../../build/toolchain/gcc_link_wrapper.py\\\" --output=...(too long)\\nld.lld: /b/s/w/ir/cache/builder/src/third_party/llvm/llvm/lib/Transforms/Vectorize/LoopVectorize.cpp:2817: void llvm::InnerLoopVectorizer::emitMemRuntimeChecks(llvm::Loop*, llvm::BasicBlock*): Assertion `Cost-\u003eHints-\u003egetForce() == LoopVectorizeHints::FK_Enabled \u0026\u0026 \\\"Cannot emit memory checks when optimizing for size, unless forced \\\" \\\"to vectorize.\\\"' failed.\\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": "1594992143612703",
"status": "COMPLETED",
"status_changed_ts": "1594992144170946",
"tags": [
"build_address:luci.chromium.ci/ToTAndroidOfficial/7666",
"builder:ToTAndroidOfficial",
"buildset:commit/git/ee54f959b14b734d67e5a403dd3ee9cc7d54dd1e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ee54f959b14b734d67e5a403dd3ee9cc7d54dd1e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9039953632712476528",
"scheduler_job_id:chromium/ToTAndroidOfficial",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8874523570506714048/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4d75bd6529ed2710",
"user_agent:luci-scheduler"
],
"updated_ts": "1594995342103913",
"url": "https://ci.chromium.org/b/8874523570506714048",
"utcnow_ts": "1594995342536394"
}
}
[14:15:42.545] Invocation finished in 58m41.358616047s with status FAILED