[12:09:04.489] New invocation is queued and will start shortly
[12:09:05.526] Starting the invocation (attempt 1)
[12:09:05.565] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:09:05.565] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:09:05.565] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9079186393936500336",
"parameters_json": "{\"builder_name\":\"Deterministic Android\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"53f7192bc387645feee4e57edb515a83a94165c7\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Deterministic Android",
"scheduler_invocation_id:9079186393936500336",
"scheduler_job_id:chromium/Deterministic Android",
"user_agent:luci-scheduler",
"buildset:commit/git/53f7192bc387645feee4e57edb515a83a94165c7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/53f7192bc387645feee4e57edb515a83a94165c7",
"gitiles_ref:refs/heads/master"
]
}
[12:09:06.961] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1557576545724488",
"experimental": true,
"id": "8913756331949523616",
"parameters_json": "{\"builder_name\": \"Deterministic Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"53f7192bc387645feee4e57edb515a83a94165c7\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1557576546674573",
"tags": [
"build_address:luci.chromium.ci/Deterministic Android/5149",
"builder:Deterministic Android",
"buildset:commit/git/53f7192bc387645feee4e57edb515a83a94165c7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/53f7192bc387645feee4e57edb515a83a94165c7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079186393936500336",
"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/8913756331949523616/+/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": "1557576546674759",
"url": "https://ci.chromium.org/b/8913756331949523616",
"utcnow_ts": "1557576546939663"
}
}
[12:09:06.961] Task URL: https://ci.chromium.org/b/8913756331949523616
[12:09:06.961] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:2:0) after 1m0s
[12:10:06.964] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:2:0)
[12:10:06.964] Timer tick, asking Buildbucket for the build status
[12:10:08.994] Build 8913756331949523616: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:10:08.994] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:3:0) after 1m0s
[12:11:09.013] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:3:0)
[12:11:09.013] Timer tick, asking Buildbucket for the build status
[12:11:09.686] Build 8913756331949523616: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:11:09.686] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:4:0) after 1m0s
[12:12:09.706] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:4:0)
[12:12:09.706] Timer tick, asking Buildbucket for the build status
[12:12:10.241] Build 8913756331949523616: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:12:10.241] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:5:0) after 1m0s
[12:13:02.704] Received PubSub notification, asking Buildbucket for the build status
[12:13:02.821] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:10.266] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:5:0)
[12:13:10.267] Timer tick, asking Buildbucket for the build status
[12:13:10.914] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:10.914] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:7:0) after 1m0s
[12:14:10.944] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:7:0)
[12:14:10.944] Timer tick, asking Buildbucket for the build status
[12:14:13.275] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:13.275] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:8:0) after 1m0s
[12:15:13.297] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:8:0)
[12:15:13.297] Timer tick, asking Buildbucket for the build status
[12:15:13.504] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:13.504] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:9:0) after 1m0s
[12:16:13.524] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:9:0)
[12:16:13.524] Timer tick, asking Buildbucket for the build status
[12:16:14.946] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:14.946] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:10:0) after 1m0s
[12:17:14.966] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:10:0)
[12:17:14.966] Timer tick, asking Buildbucket for the build status
[12:17:15.730] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:15.730] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:11:0) after 1m0s
[12:18:15.752] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:11:0)
[12:18:15.752] Timer tick, asking Buildbucket for the build status
[12:18:18.196] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:18.196] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:12:0) after 1m0s
[12:19:18.224] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:12:0)
[12:19:18.224] Timer tick, asking Buildbucket for the build status
[12:19:18.399] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:18.399] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:13:0) after 1m0s
[12:20:18.429] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:13:0)
[12:20:18.429] Timer tick, asking Buildbucket for the build status
[12:20:18.746] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:18.746] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:14:0) after 1m0s
[12:21:18.779] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:14:0)
[12:21:18.779] Timer tick, asking Buildbucket for the build status
[12:21:20.144] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:20.144] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:15:0) after 1m0s
[12:22:20.165] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:15:0)
[12:22:20.165] Timer tick, asking Buildbucket for the build status
[12:22:20.600] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:20.600] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:16:0) after 1m0s
[12:23:20.645] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:16:0)
[12:23:20.645] Timer tick, asking Buildbucket for the build status
[12:23:21.007] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:21.007] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:17:0) after 1m0s
[12:24:20.999] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:17:0)
[12:24:20.999] Timer tick, asking Buildbucket for the build status
[12:24:21.183] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:21.183] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:18:0) after 1m0s
[12:25:21.212] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:18:0)
[12:25:21.212] Timer tick, asking Buildbucket for the build status
[12:25:21.383] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:21.383] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:19:0) after 1m0s
[12:26:21.418] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:19:0)
[12:26:21.418] Timer tick, asking Buildbucket for the build status
[12:26:21.536] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:21.536] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:20:0) after 1m0s
[12:27:21.555] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:20:0)
[12:27:21.555] Timer tick, asking Buildbucket for the build status
[12:27:21.831] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:21.831] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:21:0) after 1m0s
[12:28:21.847] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:21:0)
[12:28:21.847] Timer tick, asking Buildbucket for the build status
[12:28:21.975] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:21.975] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:22:0) after 1m0s
[12:29:22.023] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:22:0)
[12:29:22.024] Timer tick, asking Buildbucket for the build status
[12:29:22.903] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:22.903] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:23:0) after 1m0s
[12:30:22.954] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:23:0)
[12:30:22.954] Timer tick, asking Buildbucket for the build status
[12:30:23.406] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:23.406] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:24:0) after 1m0s
[12:31:23.428] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:24:0)
[12:31:23.428] Timer tick, asking Buildbucket for the build status
[12:31:24.996] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:24.996] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:25:0) after 1m0s
[12:32:25.016] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:25:0)
[12:32:25.016] Timer tick, asking Buildbucket for the build status
[12:32:25.831] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:25.831] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:26:0) after 1m0s
[12:33:25.835] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:26:0)
[12:33:25.835] Timer tick, asking Buildbucket for the build status
[12:33:25.936] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:25.936] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:27:0) after 1m0s
[12:34:25.955] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:27:0)
[12:34:25.955] Timer tick, asking Buildbucket for the build status
[12:34:26.619] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:26.619] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:28:0) after 1m0s
[12:35:26.639] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:28:0)
[12:35:26.639] Timer tick, asking Buildbucket for the build status
[12:35:26.762] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:26.762] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:29:0) after 1m0s
[12:36:26.784] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:29:0)
[12:36:26.784] Timer tick, asking Buildbucket for the build status
[12:36:27.129] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:27.129] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:30:0) after 1m0s
[12:37:27.154] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:30:0)
[12:37:27.154] Timer tick, asking Buildbucket for the build status
[12:37:27.446] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:27.446] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:31:0) after 1m0s
[12:38:27.501] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:31:0)
[12:38:27.501] Timer tick, asking Buildbucket for the build status
[12:38:28.132] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:28.132] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:32:0) after 1m0s
[12:39:28.167] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:32:0)
[12:39:28.167] Timer tick, asking Buildbucket for the build status
[12:39:28.628] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:28.628] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:33:0) after 1m0s
[12:40:28.676] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:33:0)
[12:40:28.676] Timer tick, asking Buildbucket for the build status
[12:40:29.168] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:29.168] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:34:0) after 1m0s
[12:41:29.190] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:34:0)
[12:41:29.190] Timer tick, asking Buildbucket for the build status
[12:41:29.722] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:29.722] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:35:0) after 1m0s
[12:42:29.729] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:35:0)
[12:42:29.729] Timer tick, asking Buildbucket for the build status
[12:42:31.445] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:31.445] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:36:0) after 1m0s
[12:43:31.413] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:36:0)
[12:43:31.413] Timer tick, asking Buildbucket for the build status
[12:43:31.711] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:31.711] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:37:0) after 1m0s
[12:44:31.730] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:37:0)
[12:44:31.730] Timer tick, asking Buildbucket for the build status
[12:44:33.114] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:33.114] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:38:0) after 1m0s
[12:45:33.139] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:38:0)
[12:45:33.139] Timer tick, asking Buildbucket for the build status
[12:45:33.408] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:33.408] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:39:0) after 1m0s
[12:46:33.426] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:39:0)
[12:46:33.426] Timer tick, asking Buildbucket for the build status
[12:46:35.435] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:35.435] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:40:0) after 1m0s
[12:47:35.470] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:40:0)
[12:47:35.470] Timer tick, asking Buildbucket for the build status
[12:47:35.717] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:35.717] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:41:0) after 1m0s
[12:48:35.735] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:41:0)
[12:48:35.735] Timer tick, asking Buildbucket for the build status
[12:48:36.768] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:36.768] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:42:0) after 1m0s
[12:49:36.809] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:42:0)
[12:49:36.809] Timer tick, asking Buildbucket for the build status
[12:49:37.075] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:37.075] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:43:0) after 1m0s
[12:50:37.169] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:43:0)
[12:50:37.169] Timer tick, asking Buildbucket for the build status
[12:50:38.264] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:38.264] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:44:0) after 1m0s
[12:51:38.271] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:44:0)
[12:51:38.271] Timer tick, asking Buildbucket for the build status
[12:51:40.199] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:40.200] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:45:0) after 1m0s
[12:52:40.218] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:45:0)
[12:52:40.218] Timer tick, asking Buildbucket for the build status
[12:52:40.538] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:40.538] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:46:0) after 1m0s
[12:53:40.560] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:46:0)
[12:53:40.560] Timer tick, asking Buildbucket for the build status
[12:53:40.905] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:40.905] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:47:0) after 1m0s
[12:54:40.956] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:47:0)
[12:54:40.956] Timer tick, asking Buildbucket for the build status
[12:54:41.316] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:41.316] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:48:0) after 1m0s
[12:55:41.304] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:48:0)
[12:55:41.304] Timer tick, asking Buildbucket for the build status
[12:55:41.511] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:41.511] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:49:0) after 1m0s
[12:56:41.542] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:49:0)
[12:56:41.542] Timer tick, asking Buildbucket for the build status
[12:56:42.586] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:42.587] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:50:0) after 1m0s
[12:57:42.609] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:50:0)
[12:57:42.609] Timer tick, asking Buildbucket for the build status
[12:57:42.859] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:42.859] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:51:0) after 1m0s
[12:58:42.882] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:51:0)
[12:58:42.882] Timer tick, asking Buildbucket for the build status
[12:58:46.175] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:46.175] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:52:0) after 1m0s
[12:59:46.183] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:52:0)
[12:59:46.183] Timer tick, asking Buildbucket for the build status
[12:59:48.263] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:48.263] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:53:0) after 1m0s
[13:00:48.239] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:53:0)
[13:00:48.239] Timer tick, asking Buildbucket for the build status
[13:00:48.673] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:48.673] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:54:0) after 1m0s
[13:01:48.706] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:54:0)
[13:01:48.706] Timer tick, asking Buildbucket for the build status
[13:01:50.312] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:50.312] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:55:0) after 1m0s
[13:02:50.311] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:55:0)
[13:02:50.311] Timer tick, asking Buildbucket for the build status
[13:02:52.132] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:52.132] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:56:0) after 1m0s
[13:03:52.153] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:56:0)
[13:03:52.153] Timer tick, asking Buildbucket for the build status
[13:03:52.528] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:52.528] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:57:0) after 1m0s
[13:04:52.546] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:57:0)
[13:04:52.546] Timer tick, asking Buildbucket for the build status
[13:04:54.345] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:54.345] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:58:0) after 1m0s
[13:05:54.376] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:58:0)
[13:05:54.376] Timer tick, asking Buildbucket for the build status
[13:05:55.152] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:55.152] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:59:0) after 1m0s
[13:06:55.151] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:59:0)
[13:06:55.151] Timer tick, asking Buildbucket for the build status
[13:06:55.725] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:55.725] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:60:0) after 1m0s
[13:07:55.755] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:60:0)
[13:07:55.755] Timer tick, asking Buildbucket for the build status
[13:07:56.206] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:56.206] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:61:0) after 1m0s
[13:08:56.226] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:61:0)
[13:08:56.226] Timer tick, asking Buildbucket for the build status
[13:08:58.482] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:58.482] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:62:0) after 1m0s
[13:09:58.501] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:62:0)
[13:09:58.501] Timer tick, asking Buildbucket for the build status
[13:09:58.724] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:58.724] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:63:0) after 1m0s
[13:10:58.747] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:63:0)
[13:10:58.747] Timer tick, asking Buildbucket for the build status
[13:11:00.435] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:00.435] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:64:0) after 1m0s
[13:12:00.509] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:64:0)
[13:12:00.509] Timer tick, asking Buildbucket for the build status
[13:12:00.616] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:00.616] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:65:0) after 1m0s
[13:13:00.672] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:65:0)
[13:13:00.672] Timer tick, asking Buildbucket for the build status
[13:13:00.911] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:00.911] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:66:0) after 1m0s
[13:14:00.945] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:66:0)
[13:14:00.945] Timer tick, asking Buildbucket for the build status
[13:14:02.311] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:02.311] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:67:0) after 1m0s
[13:15:02.339] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:67:0)
[13:15:02.339] Timer tick, asking Buildbucket for the build status
[13:15:03.448] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:03.449] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:68:0) after 1m0s
[13:16:03.484] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:68:0)
[13:16:03.484] Timer tick, asking Buildbucket for the build status
[13:16:04.676] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:04.676] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:69:0) after 1m0s
[13:17:04.700] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:69:0)
[13:17:04.700] Timer tick, asking Buildbucket for the build status
[13:17:05.595] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:05.596] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:70:0) after 1m0s
[13:18:05.618] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:70:0)
[13:18:05.618] Timer tick, asking Buildbucket for the build status
[13:18:05.745] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:05.745] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:71:0) after 1m0s
[13:19:05.818] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:71:0)
[13:19:05.818] Timer tick, asking Buildbucket for the build status
[13:19:05.985] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:05.985] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:72:0) after 1m0s
[13:20:06.035] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:72:0)
[13:20:06.035] Timer tick, asking Buildbucket for the build status
[13:20:07.841] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:07.841] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:73:0) after 1m0s
[13:21:07.883] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:73:0)
[13:21:07.883] Timer tick, asking Buildbucket for the build status
[13:21:08.320] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:08.320] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:74:0) after 1m0s
[13:22:08.385] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:74:0)
[13:22:08.385] Timer tick, asking Buildbucket for the build status
[13:22:09.443] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:09.443] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:75:0) after 1m0s
[13:23:09.475] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:75:0)
[13:23:09.475] Timer tick, asking Buildbucket for the build status
[13:23:17.906] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:17.906] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:76:0) after 1m0s
[13:24:17.918] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:76:0)
[13:24:17.918] Timer tick, asking Buildbucket for the build status
[13:24:18.498] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:18.498] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:77:0) after 1m0s
[13:25:18.500] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:77:0)
[13:25:18.500] Timer tick, asking Buildbucket for the build status
[13:25:24.394] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:24.394] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:78:0) after 1m0s
[13:26:24.421] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:78:0)
[13:26:24.421] Timer tick, asking Buildbucket for the build status
[13:26:24.954] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:24.954] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:79:0) after 1m0s
[13:27:24.995] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:79:0)
[13:27:24.995] Timer tick, asking Buildbucket for the build status
[13:27:25.189] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:25.189] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:80:0) after 1m0s
[13:28:25.195] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:80:0)
[13:28:25.195] Timer tick, asking Buildbucket for the build status
[13:28:26.066] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:26.066] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:81:0) after 1m0s
[13:29:26.136] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:81:0)
[13:29:26.136] Timer tick, asking Buildbucket for the build status
[13:29:26.596] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:26.596] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:82:0) after 1m0s
[13:30:26.582] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:82:0)
[13:30:26.582] Timer tick, asking Buildbucket for the build status
[13:30:28.624] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:28.624] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:83:0) after 1m0s
[13:31:28.646] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:83:0)
[13:31:28.646] Timer tick, asking Buildbucket for the build status
[13:31:29.234] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:29.234] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:84:0) after 1m0s
[13:32:29.256] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:84:0)
[13:32:29.256] Timer tick, asking Buildbucket for the build status
[13:32:30.503] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:30.503] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:85:0) after 1m0s
[13:33:30.528] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:85:0)
[13:33:30.528] Timer tick, asking Buildbucket for the build status
[13:33:31.465] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:31.465] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:86:0) after 1m0s
[13:34:31.497] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:86:0)
[13:34:31.497] Timer tick, asking Buildbucket for the build status
[13:34:32.965] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:32.965] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:87:0) after 1m0s
[13:35:32.998] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:87:0)
[13:35:32.998] Timer tick, asking Buildbucket for the build status
[13:35:34.281] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:34.281] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:88:0) after 1m0s
[13:36:34.301] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:88:0)
[13:36:34.301] Timer tick, asking Buildbucket for the build status
[13:36:34.614] Build 8913756331949523616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:34.614] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9079186393936500336:89:0) after 1m0s
[13:37:34.064] Received PubSub notification, asking Buildbucket for the build status
[13:37:35.179] Build 8913756331949523616: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[13:37:35.179] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1557581852593566",
"created_by": "project:chromium",
"created_ts": "1557576545724488",
"experimental": true,
"failure_reason": "BUILD_FAILURE",
"id": "8913756331949523616",
"parameters_json": "{\"builder_name\": \"Deterministic Android\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"53f7192bc387645feee4e57edb515a83a94165c7\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Failing build because ninja reported work to do.\\n This means that after completing a compile, another was run and\\n it resulted in still having work to do (that is, a no-op build\\n wasn't a no-op). Consult the first \\\"ninja explain:\\\" line for a\\n likely culprit.\"}, \"properties\": {\"$build/goma\": {\"jobs\": 150.0}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": true, \"is_luci\": true}, \"bot_id\": \"deterministic-android-rel-0-nb4w\", \"branch\": \"refs/heads/master\", \"buildername\": \"Deterministic Android\", \"buildnumber\": 5149, \"got_angle_revision\": \"3dbe480b559e7919f303a15b4d4b0c788229c3ee\", \"got_dawn_revision\": \"97f08fa2e68f3505384395a8b6594fb70bdec6a3\", \"got_nacl_revision\": \"ca374f5112fca6b36fc5d73e117a60b82f394993\", \"got_revision\": \"53f7192bc387645feee4e57edb515a83a94165c7\", \"got_revision_cp\": \"refs/heads/master@{#658890}\", \"got_swarming_client_revision\": \"1b65f4e862045f3ba430a4cbd0643f5b1b66c230\", \"got_v8_revision\": \"512e74e0bdb9d98c4d729551c294127fff4b0434\", \"got_v8_revision_cp\": \"refs/heads/7.6.160@{#1}\", \"got_webrtc_revision\": \"46afbf9481fbcc939c998c898ca1031ce41cc6b1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27917}\", \"mastername\": \"chromium.android\", \"path_config\": \"generic\", \"recipe\": \"swarming/deterministic_build\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"53f7192bc387645feee4e57edb515a83a94165c7\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"Deterministic Android\"], \"caches\": [\"builder_0442f466ee54a95cfb510061921bb277cbb8d5a6f79e09e045baf9198c2b257e_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"deterministic-android-rel-0-nb4w\"], \"image\": [\"chrome-trusty-19041600-95c8804d634\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4292-d8c05fa\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}, \"ui\": {\"info\": \"Failing build because ninja reported work to do.\\n This means that after completing a compile, another was run and\\n it resulted in still having work to do (that is, a no-op build\\n wasn't a no-op). Consult the first \\\"ninja explain:\\\" line for a\\n likely culprit.\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1557576781573439",
"status": "COMPLETED",
"status_changed_ts": "1557576782300213",
"tags": [
"build_address:luci.chromium.ci/Deterministic Android/5149",
"builder:Deterministic Android",
"buildset:commit/git/53f7192bc387645feee4e57edb515a83a94165c7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/53f7192bc387645feee4e57edb515a83a94165c7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9079186393936500336",
"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/8913756331949523616/+/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:44bf9d82b5aea110",
"user_agent:luci-scheduler"
],
"updated_ts": "1557581853263830",
"url": "https://ci.chromium.org/b/8913756331949523616",
"utcnow_ts": "1557581855163157"
}
}
[13:37:35.179] Invocation finished in 1h28m30.733626854s with status FAILED