[14:59:40.295] New invocation is queued and will start shortly
[14:59:41.482] Starting the invocation (attempt 1)
[14:59:41.513] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:59:41.513] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:59:41.513] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9044567619721737936",
"parameters_json": "{\"builder_name\":\"ToTAndroid\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"cdb6b36a2e74dbdb0c948a83b08f1d254aa0c424\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:ToTAndroid",
"scheduler_invocation_id:9044567619721737936",
"scheduler_job_id:chromium/ToTAndroid",
"user_agent:luci-scheduler",
"buildset:commit/git/cdb6b36a2e74dbdb0c948a83b08f1d254aa0c424",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cdb6b36a2e74dbdb0c948a83b08f1d254aa0c424",
"gitiles_ref:refs/heads/master"
]
}
[14:59:42.335] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1590591581659189",
"id": "8879137557628099392",
"parameters_json": "{\"builder_name\": \"ToTAndroid\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cdb6b36a2e74dbdb0c948a83b08f1d254aa0c424\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1590591582162096",
"tags": [
"build_address:luci.chromium.ci/ToTAndroid/12279",
"builder:ToTAndroid",
"buildset:commit/git/cdb6b36a2e74dbdb0c948a83b08f1d254aa0c424",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cdb6b36a2e74dbdb0c948a83b08f1d254aa0c424",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9044567619721737936",
"scheduler_job_id:chromium/ToTAndroid",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8879137557628099392/+/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": "1590591582162216",
"url": "https://ci.chromium.org/b/8879137557628099392",
"utcnow_ts": "1590591582325885"
}
}
[14:59:42.335] Task URL: https://ci.chromium.org/b/8879137557628099392
[14:59:42.335] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:2:0) after 1m0s
[15:00:42.436] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:2:0)
[15:00:42.436] Timer tick, asking Buildbucket for the build status
[15:00:43.002] Build 8879137557628099392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:00:43.002] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:3:0) after 1m0s
[15:01:09.426] Received PubSub notification, asking Buildbucket for the build status
[15:01:09.544] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:43.080] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:3:0)
[15:01:43.080] Timer tick, asking Buildbucket for the build status
[15:01:43.424] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:43.424] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:5:0) after 1m0s
[15:02:43.530] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:5:0)
[15:02:43.530] Timer tick, asking Buildbucket for the build status
[15:02:45.587] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:45.587] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:6:0) after 1m0s
[15:03:45.675] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:6:0)
[15:03:45.675] Timer tick, asking Buildbucket for the build status
[15:03:45.775] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:45.775] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:7:0) after 1m0s
[15:04:45.839] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:7:0)
[15:04:45.839] Timer tick, asking Buildbucket for the build status
[15:04:46.063] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:46.063] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:8:0) after 1m0s
[15:05:46.157] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:8:0)
[15:05:46.157] Timer tick, asking Buildbucket for the build status
[15:05:46.845] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:46.845] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:9:0) after 1m0s
[15:06:46.864] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:9:0)
[15:06:46.864] Timer tick, asking Buildbucket for the build status
[15:06:46.986] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:46.986] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:10:0) after 1m0s
[15:07:47.032] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:10:0)
[15:07:47.032] Timer tick, asking Buildbucket for the build status
[15:07:47.199] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:47.199] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:11:0) after 1m0s
[15:08:47.255] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:11:0)
[15:08:47.255] Timer tick, asking Buildbucket for the build status
[15:08:47.395] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:47.395] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:12:0) after 1m0s
[15:09:47.464] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:12:0)
[15:09:47.465] Timer tick, asking Buildbucket for the build status
[15:09:48.088] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:48.088] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:13:0) after 1m0s
[15:10:48.235] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:13:0)
[15:10:48.235] Timer tick, asking Buildbucket for the build status
[15:10:52.005] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:52.005] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:14:0) after 1m0s
[15:11:52.324] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:14:0)
[15:11:52.324] Timer tick, asking Buildbucket for the build status
[15:11:52.562] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:52.562] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:15:0) after 1m0s
[15:12:52.735] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:15:0)
[15:12:52.735] Timer tick, asking Buildbucket for the build status
[15:12:52.943] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:52.943] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:16:0) after 1m0s
[15:13:53.195] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:16:0)
[15:13:53.195] Timer tick, asking Buildbucket for the build status
[15:13:53.712] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:53.712] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:17:0) after 1m0s
[15:14:53.816] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:17:0)
[15:14:53.816] Timer tick, asking Buildbucket for the build status
[15:14:54.009] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:54.009] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:18:0) after 1m0s
[15:15:54.040] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:18:0)
[15:15:54.040] Timer tick, asking Buildbucket for the build status
[15:15:58.698] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:58.699] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:19:0) after 1m0s
[15:16:59.330] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:19:0)
[15:16:59.330] Timer tick, asking Buildbucket for the build status
[15:16:59.531] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:59.531] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:20:0) after 1m0s
[15:17:59.705] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:20:0)
[15:17:59.705] Timer tick, asking Buildbucket for the build status
[15:17:59.876] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:59.876] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:21:0) after 1m0s
[15:18:59.853] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:21:0)
[15:18:59.853] Timer tick, asking Buildbucket for the build status
[15:18:59.999] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:59.999] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:22:0) after 1m0s
[15:20:00.037] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:22:0)
[15:20:00.037] Timer tick, asking Buildbucket for the build status
[15:20:00.354] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:00.354] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:23:0) after 1m0s
[15:21:01.756] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:23:0)
[15:21:01.756] Timer tick, asking Buildbucket for the build status
[15:21:01.917] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:01.917] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:24:0) after 1m0s
[15:22:02.510] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:24:0)
[15:22:02.510] Timer tick, asking Buildbucket for the build status
[15:22:02.774] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:02.774] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:25:0) after 1m0s
[15:23:02.904] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:25:0)
[15:23:02.904] Timer tick, asking Buildbucket for the build status
[15:23:03.099] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:03.099] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:26:0) after 1m0s
[15:24:03.136] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:26:0)
[15:24:03.136] Timer tick, asking Buildbucket for the build status
[15:24:03.860] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:03.860] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:27:0) after 1m0s
[15:25:03.892] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:27:0)
[15:25:03.892] Timer tick, asking Buildbucket for the build status
[15:25:04.225] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:04.225] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:28:0) after 1m0s
[15:26:04.291] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:28:0)
[15:26:04.291] Timer tick, asking Buildbucket for the build status
[15:26:04.500] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:04.500] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:29:0) after 1m0s
[15:27:04.512] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:29:0)
[15:27:04.512] Timer tick, asking Buildbucket for the build status
[15:27:04.821] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:04.821] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:30:0) after 1m0s
[15:28:04.860] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:30:0)
[15:28:04.860] Timer tick, asking Buildbucket for the build status
[15:28:05.171] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:05.171] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:31:0) after 1m0s
[15:29:05.234] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:31:0)
[15:29:05.234] Timer tick, asking Buildbucket for the build status
[15:29:05.409] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:05.409] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:32:0) after 1m0s
[15:30:05.474] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:32:0)
[15:30:05.474] Timer tick, asking Buildbucket for the build status
[15:30:05.741] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:05.741] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:33:0) after 1m0s
[15:31:05.763] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:33:0)
[15:31:05.763] Timer tick, asking Buildbucket for the build status
[15:31:05.956] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:05.956] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:34:0) after 1m0s
[15:32:06.034] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:34:0)
[15:32:06.034] Timer tick, asking Buildbucket for the build status
[15:32:06.257] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:06.257] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:35:0) after 1m0s
[15:33:06.307] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:35:0)
[15:33:06.307] Timer tick, asking Buildbucket for the build status
[15:33:06.717] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:06.717] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:36:0) after 1m0s
[15:34:07.967] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:36:0)
[15:34:07.967] Timer tick, asking Buildbucket for the build status
[15:34:08.073] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:08.073] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:37:0) after 1m0s
[15:35:08.231] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:37:0)
[15:35:08.231] Timer tick, asking Buildbucket for the build status
[15:35:08.693] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:08.693] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:38:0) after 1m0s
[15:36:09.000] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:38:0)
[15:36:09.000] Timer tick, asking Buildbucket for the build status
[15:36:09.356] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:09.356] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:39:0) after 1m0s
[15:37:09.589] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:39:0)
[15:37:09.589] Timer tick, asking Buildbucket for the build status
[15:37:09.808] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:09.808] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:40:0) after 1m0s
[15:38:09.965] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:40:0)
[15:38:09.965] Timer tick, asking Buildbucket for the build status
[15:38:10.114] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:10.114] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:41:0) after 1m0s
[15:39:10.229] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:41:0)
[15:39:10.229] Timer tick, asking Buildbucket for the build status
[15:39:10.597] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:10.597] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:42:0) after 1m0s
[15:40:11.040] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:42:0)
[15:40:11.040] Timer tick, asking Buildbucket for the build status
[15:40:11.202] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:11.202] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:43:0) after 1m0s
[15:41:11.447] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:43:0)
[15:41:11.447] Timer tick, asking Buildbucket for the build status
[15:41:11.610] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:11.610] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:44:0) after 1m0s
[15:42:12.054] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:44:0)
[15:42:12.054] Timer tick, asking Buildbucket for the build status
[15:42:12.235] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:12.235] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:45:0) after 1m0s
[15:43:12.570] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:45:0)
[15:43:12.570] Timer tick, asking Buildbucket for the build status
[15:43:12.721] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:12.721] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:46:0) after 1m0s
[15:44:12.970] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:46:0)
[15:44:12.970] Timer tick, asking Buildbucket for the build status
[15:44:13.883] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:13.883] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:47:0) after 1m0s
[15:45:14.043] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:47:0)
[15:45:14.043] Timer tick, asking Buildbucket for the build status
[15:45:14.611] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:14.612] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:48:0) after 1m0s
[15:46:14.929] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:48:0)
[15:46:14.929] Timer tick, asking Buildbucket for the build status
[15:46:15.337] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:15.337] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:49:0) after 1m0s
[15:47:15.460] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:49:0)
[15:47:15.460] Timer tick, asking Buildbucket for the build status
[15:47:15.631] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:15.631] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:50:0) after 1m0s
[15:48:15.824] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:50:0)
[15:48:15.824] Timer tick, asking Buildbucket for the build status
[15:48:16.060] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:16.060] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:51:0) after 1m0s
[15:49:16.267] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:51:0)
[15:49:16.267] Timer tick, asking Buildbucket for the build status
[15:49:16.576] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:16.576] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:52:0) after 1m0s
[15:50:16.901] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:52:0)
[15:50:16.901] Timer tick, asking Buildbucket for the build status
[15:50:17.203] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:17.203] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:53:0) after 1m0s
[15:51:17.740] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:53:0)
[15:51:17.740] Timer tick, asking Buildbucket for the build status
[15:51:17.932] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:17.932] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:54:0) after 1m0s
[15:52:17.980] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:54:0)
[15:52:17.980] Timer tick, asking Buildbucket for the build status
[15:52:18.185] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:18.185] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:55:0) after 1m0s
[15:53:18.239] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:55:0)
[15:53:18.239] Timer tick, asking Buildbucket for the build status
[15:53:18.360] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:18.360] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:56:0) after 1m0s
[15:54:18.425] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:56:0)
[15:54:18.425] Timer tick, asking Buildbucket for the build status
[15:54:18.567] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:18.567] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:57:0) after 1m0s
[15:55:18.628] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:57:0)
[15:55:18.628] Timer tick, asking Buildbucket for the build status
[15:55:18.773] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:18.773] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:58:0) after 1m0s
[15:56:18.779] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:58:0)
[15:56:18.779] Timer tick, asking Buildbucket for the build status
[15:56:21.201] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:21.201] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:59:0) after 1m0s
[15:57:21.195] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:59:0)
[15:57:21.195] Timer tick, asking Buildbucket for the build status
[15:57:21.312] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:21.312] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:60:0) after 1m0s
[15:58:21.386] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:60:0)
[15:58:21.386] Timer tick, asking Buildbucket for the build status
[15:58:21.740] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:21.740] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:61:0) after 1m0s
[15:59:21.765] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:61:0)
[15:59:21.765] Timer tick, asking Buildbucket for the build status
[15:59:21.919] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:21.919] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:62:0) after 1m0s
[16:00:22.041] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:62:0)
[16:00:22.041] Timer tick, asking Buildbucket for the build status
[16:00:24.506] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:24.506] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:63:0) after 1m0s
[16:01:24.585] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:63:0)
[16:01:24.585] Timer tick, asking Buildbucket for the build status
[16:01:25.137] Build 8879137557628099392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:25.137] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid:9044567619721737936:64:0) after 1m0s
[16:02:09.024] Received PubSub notification, asking Buildbucket for the build status
[16:02:09.165] Build 8879137557628099392: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[16:02:09.165] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1590595328264676",
"created_by": "project:chromium",
"created_ts": "1590591581659189",
"failure_reason": "BUILD_FAILURE",
"id": "8879137557628099392",
"parameters_json": "{\"builder_name\": \"ToTAndroid\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cdb6b36a2e74dbdb0c948a83b08f1d254aa0c424\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"#### Step _compile_ failed. Error logs are shown below:\\n```\\n[18048/79371] CC clang_x64/obj/third_party/ffmpeg/ffmpeg_internal/h264_cabac.o\\nFAILED: clang_x64/obj/third_party/ffmpeg/ffmpeg_internal/h264_cabac.o\\n../../third_party/llvm-build/Release+Asserts/bin/clang -MMD -MF clang_x64/obj/third_party/ffmpeg/ffm...(too long)\\nIn file included from ../../third_party/ffmpeg/libavcodec/h264_cabac.c:44:\\n../../third_party/ffmpeg/libavcodec/x86/h264_cabac.c:145:51: error: unknown use of instruction mnemonic without a size suffix\\n\\\"mov %10, %0 \\\\n\\\\t\\\"\\n^\\n\u003cinline asm\u003e:4:2: note: instantiated into assembly here\\nmovzb (%r14, %r8), %r8\\n^\\nIn file included from ../../third_party/ffmpeg/libavcodec/h264_cabac.c:44:\\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-32-1-kniz\", \"branch\": \"refs/heads/master\", \"buildername\": \"ToTAndroid\", \"buildnumber\": 12279, \"clang_revision\": \"n355345-b5b00877-0\", \"got_angle_revision\": \"4a81e3dba0fd0d6e54987156486265f82e1382cd\", \"got_dawn_revision\": \"55c85f66d2fd201717ce667bf96c0949b32e9623\", \"got_nacl_revision\": \"87616f7ce2ea8383efd3731960c15f4ba5852d93\", \"got_revision\": \"cdb6b36a2e74dbdb0c948a83b08f1d254aa0c424\", \"got_revision_cp\": \"refs/heads/master@{#772239}\", \"got_swarming_client_revision\": \"90c5e17a82612bc898c90ab1530dd1bd5822eae8\", \"got_swiftshader_revision\": \"9e718f962f87c30d08e91053f0e9ce3467cbd488\", \"got_v8_revision\": \"d0a8b04f7c967c327a4595d96eb1c67465d72c11\", \"got_v8_revision_cp\": \"refs/heads/8.5.48@{#1}\", \"got_webrtc_revision\": \"014197b581a3422eee9fb9f29f53cf95a4eb45b2\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31364}\", \"mastername\": \"chromium.clang\", \"path_config\": \"generic\", \"perf_dashboard_machine_group\": \"ChromiumClang\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cdb6b36a2e74dbdb0c948a83b08f1d254aa0c424\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0264569d20b29e1d5e6df044c8d1223ed84f9f22180c83dac41e8db0ff2b71db_v2\", \"builder_02c489102c6b23f720d825d3d639a9c0a891978418b04ce86081f51b1a63cfd3_v2\", \"builder_17d4e9c3562fe440e7ef7dabcb522d5a89809ef05519650a163dd2bc405c0258_v2\", \"builder_4071df92b94331eb3da38507368746e2a4f045780701cbd95f86ba21c63ad0a4_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-32-1-kniz\"], \"image\": [\"chrome-xenial-20041200-f56df5dd7ad\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5134-d5e72d0\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-c\"]}}, \"ui\": {\"info\": \"#### Step _compile_ failed. Error logs are shown below:\\n```\\n[18048/79371] CC clang_x64/obj/third_party/ffmpeg/ffmpeg_internal/h264_cabac.o\\nFAILED: clang_x64/obj/third_party/ffmpeg/ffmpeg_internal/h264_cabac.o\\n../../third_party/llvm-build/Release+Asserts/bin/clang -MMD -MF clang_x64/obj/third_party/ffmpeg/ffm...(too long)\\nIn file included from ../../third_party/ffmpeg/libavcodec/h264_cabac.c:44:\\n../../third_party/ffmpeg/libavcodec/x86/h264_cabac.c:145:51: error: unknown use of instruction mnemonic without a size suffix\\n\\\"mov %10, %0 \\\\n\\\\t\\\"\\n^\\n\u003cinline asm\u003e:4:2: note: instantiated into assembly here\\nmovzb (%r14, %r8), %r8\\n^\\nIn file included from ../../third_party/ffmpeg/libavcodec/h264_cabac.c:44:\\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": "1590591668620366",
"status": "COMPLETED",
"status_changed_ts": "1590591669154856",
"tags": [
"build_address:luci.chromium.ci/ToTAndroid/12279",
"builder:ToTAndroid",
"buildset:commit/git/cdb6b36a2e74dbdb0c948a83b08f1d254aa0c424",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cdb6b36a2e74dbdb0c948a83b08f1d254aa0c424",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9044567619721737936",
"scheduler_job_id:chromium/ToTAndroid",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8879137557628099392/+/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:4c6f76f94291e510",
"user_agent:luci-scheduler"
],
"updated_ts": "1590595328610159",
"url": "https://ci.chromium.org/b/8879137557628099392",
"utcnow_ts": "1590595329153159"
}
}
[16:02:09.165] Invocation finished in 1h2m28.885811525s with status FAILED