[06:13:56.737] New invocation is queued and will start shortly
[06:13:57.822] Starting the invocation (attempt 1)
[06:13:57.863] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:13:57.863] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:13:57.863] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9063263670631807792",
"parameters_json": "{\"builder_name\":\"Deterministic Android\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"9b1692af2fd6ebc7af1085a86634d64d2b4ca320\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Android",
"scheduler_invocation_id:9063263670631807792",
"scheduler_job_id:chromium/Deterministic Android",
"user_agent:luci-scheduler",
"buildset:commit/git/9b1692af2fd6ebc7af1085a86634d64d2b4ca320",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9b1692af2fd6ebc7af1085a86634d64d2b4ca320",
"gitiles_ref:refs/heads/master"
]
}
[06:13:59.391] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1572761638507492",
"id": "8897833608098813968",
"parameters_json": "{\"builder_name\": \"Deterministic Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9b1692af2fd6ebc7af1085a86634d64d2b4ca320\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1572761639205308",
"tags": [
"build_address:luci.chromium.ci/Deterministic Android/6347",
"builder:Deterministic Android",
"buildset:commit/git/9b1692af2fd6ebc7af1085a86634d64d2b4ca320",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9b1692af2fd6ebc7af1085a86634d64d2b4ca320",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9063263670631807792",
"scheduler_job_id:chromium/Deterministic Android",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8897833608098813968/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1572761639205409",
"url": "https://ci.chromium.org/b/8897833608098813968",
"utcnow_ts": "1572761639374279"
}
}
[06:13:59.391] Task URL: https://ci.chromium.org/b/8897833608098813968
[06:13:59.391] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:2:0) after 1m0s
[06:14:04.270] Received PubSub notification, asking Buildbucket for the build status
[06:14:04.808] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:59.453] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:2:0)
[06:14:59.454] Timer tick, asking Buildbucket for the build status
[06:14:59.636] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:59.636] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:4:0) after 1m0s
[06:15:59.726] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:4:0)
[06:15:59.726] Timer tick, asking Buildbucket for the build status
[06:16:00.437] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:00.437] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:5:0) after 1m0s
[06:17:00.598] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:5:0)
[06:17:00.598] Timer tick, asking Buildbucket for the build status
[06:17:00.882] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:00.882] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:6:0) after 1m0s
[06:18:00.957] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:6:0)
[06:18:00.957] Timer tick, asking Buildbucket for the build status
[06:18:01.200] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:01.200] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:7:0) after 1m0s
[06:19:01.260] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:7:0)
[06:19:01.260] Timer tick, asking Buildbucket for the build status
[06:19:02.230] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:02.230] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:8:0) after 1m0s
[06:20:02.270] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:8:0)
[06:20:02.270] Timer tick, asking Buildbucket for the build status
[06:20:02.620] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:02.620] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:9:0) after 1m0s
[06:21:02.944] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:9:0)
[06:21:02.945] Timer tick, asking Buildbucket for the build status
[06:21:03.150] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:03.151] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:10:0) after 1m0s
[06:22:03.209] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:10:0)
[06:22:03.209] Timer tick, asking Buildbucket for the build status
[06:22:03.360] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:03.360] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:11:0) after 1m0s
[06:23:03.553] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:11:0)
[06:23:03.553] Timer tick, asking Buildbucket for the build status
[06:23:04.466] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:04.466] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:12:0) after 1m0s
[06:24:04.636] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:12:0)
[06:24:04.636] Timer tick, asking Buildbucket for the build status
[06:24:05.507] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:05.507] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:13:0) after 1m0s
[06:25:05.719] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:13:0)
[06:25:05.719] Timer tick, asking Buildbucket for the build status
[06:25:05.957] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:05.957] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:14:0) after 1m0s
[06:26:06.157] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:14:0)
[06:26:06.157] Timer tick, asking Buildbucket for the build status
[06:26:06.390] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:06.390] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:15:0) after 1m0s
[06:27:07.905] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:15:0)
[06:27:07.905] Timer tick, asking Buildbucket for the build status
[06:27:08.224] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:08.224] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:16:0) after 1m0s
[06:28:08.299] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:16:0)
[06:28:08.299] Timer tick, asking Buildbucket for the build status
[06:28:08.786] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:08.786] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:17:0) after 1m0s
[06:29:08.816] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:17:0)
[06:29:08.816] Timer tick, asking Buildbucket for the build status
[06:29:09.483] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:09.483] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:18:0) after 1m0s
[06:30:09.505] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:18:0)
[06:30:09.505] Timer tick, asking Buildbucket for the build status
[06:30:09.882] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:09.882] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:19:0) after 1m0s
[06:31:09.904] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:19:0)
[06:31:09.904] Timer tick, asking Buildbucket for the build status
[06:31:10.038] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:10.038] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:20:0) after 1m0s
[06:32:10.066] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:20:0)
[06:32:10.066] Timer tick, asking Buildbucket for the build status
[06:32:10.354] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:10.354] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:21:0) after 1m0s
[06:33:10.415] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:21:0)
[06:33:10.415] Timer tick, asking Buildbucket for the build status
[06:33:10.899] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:10.899] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:22:0) after 1m0s
[06:34:10.938] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:22:0)
[06:34:10.938] Timer tick, asking Buildbucket for the build status
[06:34:12.415] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:12.415] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:23:0) after 1m0s
[06:35:12.482] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:23:0)
[06:35:12.482] Timer tick, asking Buildbucket for the build status
[06:35:12.676] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:12.676] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:24:0) after 1m0s
[06:36:12.696] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:24:0)
[06:36:12.696] Timer tick, asking Buildbucket for the build status
[06:36:13.643] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:13.643] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:25:0) after 1m0s
[06:37:13.665] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:25:0)
[06:37:13.665] Timer tick, asking Buildbucket for the build status
[06:37:14.303] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:14.303] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:26:0) after 1m0s
[06:38:14.415] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:26:0)
[06:38:14.415] Timer tick, asking Buildbucket for the build status
[06:38:14.618] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:14.618] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:27:0) after 1m0s
[06:39:14.652] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:27:0)
[06:39:14.652] Timer tick, asking Buildbucket for the build status
[06:39:15.086] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:15.086] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:28:0) after 1m0s
[06:40:15.209] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:28:0)
[06:40:15.209] Timer tick, asking Buildbucket for the build status
[06:40:16.211] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:16.211] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:29:0) after 1m0s
[06:41:16.243] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:29:0)
[06:41:16.243] Timer tick, asking Buildbucket for the build status
[06:41:16.459] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:16.459] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:30:0) after 1m0s
[06:42:16.494] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:30:0)
[06:42:16.494] Timer tick, asking Buildbucket for the build status
[06:42:17.192] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:17.192] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:31:0) after 1m0s
[06:43:17.233] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:31:0)
[06:43:17.233] Timer tick, asking Buildbucket for the build status
[06:43:17.441] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:17.441] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:32:0) after 1m0s
[06:44:17.465] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:32:0)
[06:44:17.465] Timer tick, asking Buildbucket for the build status
[06:44:17.804] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:17.804] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:33:0) after 1m0s
[06:45:17.826] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:33:0)
[06:45:17.826] Timer tick, asking Buildbucket for the build status
[06:45:18.039] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:18.039] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:34:0) after 1m0s
[06:46:18.066] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:34:0)
[06:46:18.066] Timer tick, asking Buildbucket for the build status
[06:46:18.530] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:18.530] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:35:0) after 1m0s
[06:47:18.676] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:35:0)
[06:47:18.676] Timer tick, asking Buildbucket for the build status
[06:47:18.883] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:18.883] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:36:0) after 1m0s
[06:48:18.997] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:36:0)
[06:48:18.997] Timer tick, asking Buildbucket for the build status
[06:48:19.615] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:19.615] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:37:0) after 1m0s
[06:49:19.625] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:37:0)
[06:49:19.625] Timer tick, asking Buildbucket for the build status
[06:49:19.787] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:19.787] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:38:0) after 1m0s
[06:50:19.860] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:38:0)
[06:50:19.860] Timer tick, asking Buildbucket for the build status
[06:50:20.428] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:20.428] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:39:0) after 1m0s
[06:51:20.466] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:39:0)
[06:51:20.466] Timer tick, asking Buildbucket for the build status
[06:51:20.817] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:20.818] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:40:0) after 1m0s
[06:52:20.912] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:40:0)
[06:52:20.912] Timer tick, asking Buildbucket for the build status
[06:52:21.988] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:21.988] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:41:0) after 1m0s
[06:53:22.028] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:41:0)
[06:53:22.028] Timer tick, asking Buildbucket for the build status
[06:53:23.649] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:23.649] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:42:0) after 1m0s
[06:54:23.672] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:42:0)
[06:54:23.672] Timer tick, asking Buildbucket for the build status
[06:54:23.923] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:23.923] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:43:0) after 1m0s
[06:55:23.946] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:43:0)
[06:55:23.946] Timer tick, asking Buildbucket for the build status
[06:55:24.409] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:24.409] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:44:0) after 1m0s
[06:56:24.525] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:44:0)
[06:56:24.525] Timer tick, asking Buildbucket for the build status
[06:56:24.999] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:24.999] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:45:0) after 1m0s
[06:57:25.041] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:45:0)
[06:57:25.041] Timer tick, asking Buildbucket for the build status
[06:57:25.469] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:25.469] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:46:0) after 1m0s
[06:58:25.574] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:46:0)
[06:58:25.574] Timer tick, asking Buildbucket for the build status
[06:58:26.647] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:26.647] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:47:0) after 1m0s
[06:59:26.722] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:47:0)
[06:59:26.722] Timer tick, asking Buildbucket for the build status
[06:59:26.876] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:26.876] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:48:0) after 1m0s
[07:00:26.922] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:48:0)
[07:00:26.922] Timer tick, asking Buildbucket for the build status
[07:00:28.627] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:28.627] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:49:0) after 1m0s
[07:01:29.075] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:49:0)
[07:01:29.075] Timer tick, asking Buildbucket for the build status
[07:01:30.342] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:30.342] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:50:0) after 1m0s
[07:02:30.610] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:50:0)
[07:02:30.610] Timer tick, asking Buildbucket for the build status
[07:02:32.567] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:32.567] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:51:0) after 1m0s
[07:03:33.021] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:51:0)
[07:03:33.022] Timer tick, asking Buildbucket for the build status
[07:03:33.291] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:33.291] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:52:0) after 1m0s
[07:04:33.452] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:52:0)
[07:04:33.452] Timer tick, asking Buildbucket for the build status
[07:04:34.281] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:34.281] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:53:0) after 1m0s
[07:05:34.392] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:53:0)
[07:05:34.392] Timer tick, asking Buildbucket for the build status
[07:05:34.990] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:34.990] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:54:0) after 1m0s
[07:06:35.213] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:54:0)
[07:06:35.213] Timer tick, asking Buildbucket for the build status
[07:06:35.576] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:35.576] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:55:0) after 1m0s
[07:07:35.912] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:55:0)
[07:07:35.912] Timer tick, asking Buildbucket for the build status
[07:07:36.150] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:36.150] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:56:0) after 1m0s
[07:08:36.226] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:56:0)
[07:08:36.226] Timer tick, asking Buildbucket for the build status
[07:08:36.519] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:36.519] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:57:0) after 1m0s
[07:09:36.667] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:57:0)
[07:09:36.667] Timer tick, asking Buildbucket for the build status
[07:09:37.501] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:37.501] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:58:0) after 1m0s
[07:10:37.565] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:58:0)
[07:10:37.565] Timer tick, asking Buildbucket for the build status
[07:10:38.214] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:38.214] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:59:0) after 1m0s
[07:11:38.235] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:59:0)
[07:11:38.235] Timer tick, asking Buildbucket for the build status
[07:11:38.706] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:38.706] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:60:0) after 1m0s
[07:12:38.748] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:60:0)
[07:12:38.748] Timer tick, asking Buildbucket for the build status
[07:12:39.531] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:39.531] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:61:0) after 1m0s
[07:13:39.677] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:61:0)
[07:13:39.677] Timer tick, asking Buildbucket for the build status
[07:13:39.892] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:39.892] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:62:0) after 1m0s
[07:14:39.959] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:62:0)
[07:14:39.959] Timer tick, asking Buildbucket for the build status
[07:14:40.792] Build 8897833608098813968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:40.792] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9063263670631807792:63:0) after 1m0s
[07:15:19.661] Received PubSub notification, asking Buildbucket for the build status
[07:15:20.094] Build 8897833608098813968: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[07:15:20.094] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1572765317663809",
"created_by": "project:chromium",
"created_ts": "1572761638507492",
"failure_reason": "INFRA_FAILURE",
"id": "8897833608098813968",
"parameters_json": "{\"builder_name\": \"Deterministic Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9b1692af2fd6ebc7af1085a86634d64d2b4ca320\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"jobs\": 150.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$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-east1-d-8-22-a84f\", \"branch\": \"refs/heads/master\", \"buildername\": \"Deterministic Android\", \"buildnumber\": 6347, \"got_angle_revision\": \"b68a279ce188cc0f3920276afbd610c6ec4f7a84\", \"got_dawn_revision\": \"314fd3513d04fdc7412df0b6c7defc7b79356e8d\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"9b1692af2fd6ebc7af1085a86634d64d2b4ca320\", \"got_revision_cp\": \"refs/heads/master@{#712023}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"aaa64b76c0b40c2958a18cfdc623157c8c6e1b7d\", \"got_v8_revision\": \"53ede12e867e12ed12a5d257cf69bd2c6abe9ab9\", \"got_v8_revision_cp\": \"refs/heads/8.0.137@{#1}\", \"got_webrtc_revision\": \"8846c8af8511b505c4b61cefd4db49d15871baa7\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#29675}\", \"mastername\": \"chromium.android\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9b1692af2fd6ebc7af1085a86634d64d2b4ca320\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0442f466ee54a95cfb510061921bb277cbb8d5a6f79e09e045baf9198c2b257e_v2\", \"builder_53a0dbabd2cebac1b8e64bcbfab336eb0021d1dc40b2d4ba5321eb0a070d508c_v2\", \"builder_86caf262f74ae694bec00fbb86541daa2df96eb35c1a4d9a06c3c1dcd129237e_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-22-a84f\"], \"image\": [\"chrome-xenial-19102400-d35fedf5a0d\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4621-f8a5b1c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1572761642954803",
"status": "COMPLETED",
"status_changed_ts": "1572765318434203",
"tags": [
"build_address:luci.chromium.ci/Deterministic Android/6347",
"builder:Deterministic Android",
"buildset:commit/git/9b1692af2fd6ebc7af1085a86634d64d2b4ca320",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9b1692af2fd6ebc7af1085a86634d64d2b4ca320",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9063263670631807792",
"scheduler_job_id:chromium/Deterministic Android",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8897833608098813968/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4848b7a3b4951a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1572765318542570",
"url": "https://ci.chromium.org/b/8897833608098813968",
"utcnow_ts": "1572765320067899"
}
}
[07:15:20.094] Invocation finished in 1h1m23.385646616s with status FAILED