[16:09:24.884] New invocation is queued and will start shortly
[16:09:26.140] Starting the invocation (attempt 1)
[16:09:26.193] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:09:26.193] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:09:26.193] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9024269511390600544",
"parameters_json": "{\"builder_name\":\"android-bfcache-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f19e613c8658e4d123d02cbc3eb9cadd832c4821\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:android-bfcache-rel",
"scheduler_invocation_id:9024269511390600544",
"scheduler_job_id:chromium/android-bfcache-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/f19e613c8658e4d123d02cbc3eb9cadd832c4821",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f19e613c8658e4d123d02cbc3eb9cadd832c4821",
"gitiles_ref:refs/heads/master"
]
}
[16:09:27.014] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1609949366324888",
"id": "8858839449215401648",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f19e613c8658e4d123d02cbc3eb9cadd832c4821\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1609949366742643",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/7126",
"builder:android-bfcache-rel",
"buildset:commit/git/f19e613c8658e4d123d02cbc3eb9cadd832c4821",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f19e613c8658e4d123d02cbc3eb9cadd832c4821",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024269511390600544",
"scheduler_job_id:chromium/android-bfcache-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8858839449215401648/+/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": "1609949366742831",
"url": "https://ci.chromium.org/b/8858839449215401648",
"utcnow_ts": "1609949367007029"
}
}
[16:09:27.014] Task URL: https://ci.chromium.org/b/8858839449215401648
[16:09:27.014] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:2:0) after 1m0s
[16:10:27.072] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:2:0)
[16:10:27.072] Timer tick, asking Buildbucket for the build status
[16:10:27.279] Build 8858839449215401648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:10:27.279] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:3:0) after 1m0s
[16:11:27.297] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:3:0)
[16:11:27.297] Timer tick, asking Buildbucket for the build status
[16:11:27.443] Build 8858839449215401648: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:11:27.443] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:4:0) after 1m0s
[16:11:39.891] Received PubSub notification, asking Buildbucket for the build status
[16:11:40.230] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:27.498] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:4:0)
[16:12:27.498] Timer tick, asking Buildbucket for the build status
[16:12:27.723] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:27.723] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:6:0) after 1m0s
[16:13:27.947] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:6:0)
[16:13:27.947] Timer tick, asking Buildbucket for the build status
[16:13:28.161] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:28.162] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:7:0) after 1m0s
[16:14:28.249] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:7:0)
[16:14:28.249] Timer tick, asking Buildbucket for the build status
[16:14:28.428] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:28.428] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:8:0) after 1m0s
[16:15:28.498] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:8:0)
[16:15:28.498] Timer tick, asking Buildbucket for the build status
[16:15:28.626] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:28.626] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:9:0) after 1m0s
[16:16:28.685] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:9:0)
[16:16:28.685] Timer tick, asking Buildbucket for the build status
[16:16:28.860] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:28.860] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:10:0) after 1m0s
[16:17:28.883] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:10:0)
[16:17:28.883] Timer tick, asking Buildbucket for the build status
[16:17:29.502] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:29.502] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:11:0) after 1m0s
[16:18:29.750] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:11:0)
[16:18:29.750] Timer tick, asking Buildbucket for the build status
[16:18:30.383] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:30.383] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:12:0) after 1m0s
[16:19:30.406] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:12:0)
[16:19:30.406] Timer tick, asking Buildbucket for the build status
[16:19:30.853] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:30.853] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:13:0) after 1m0s
[16:20:30.922] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:13:0)
[16:20:30.922] Timer tick, asking Buildbucket for the build status
[16:20:31.495] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:31.495] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:14:0) after 1m0s
[16:21:31.671] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:14:0)
[16:21:31.671] Timer tick, asking Buildbucket for the build status
[16:21:31.736] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:31.736] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:15:0) after 1m0s
[16:22:31.794] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:15:0)
[16:22:31.794] Timer tick, asking Buildbucket for the build status
[16:22:31.869] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:31.869] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:16:0) after 1m0s
[16:23:31.946] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:16:0)
[16:23:31.946] Timer tick, asking Buildbucket for the build status
[16:23:32.009] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:32.009] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:17:0) after 1m0s
[16:24:32.074] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:17:0)
[16:24:32.074] Timer tick, asking Buildbucket for the build status
[16:24:32.203] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:32.203] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:18:0) after 1m0s
[16:25:32.298] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:18:0)
[16:25:32.298] Timer tick, asking Buildbucket for the build status
[16:25:32.379] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:32.379] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:19:0) after 1m0s
[16:26:32.367] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:19:0)
[16:26:32.367] Timer tick, asking Buildbucket for the build status
[16:26:32.458] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:32.458] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:20:0) after 1m0s
[16:27:32.569] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:20:0)
[16:27:32.569] Timer tick, asking Buildbucket for the build status
[16:27:32.633] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:32.633] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:21:0) after 1m0s
[16:28:32.670] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:21:0)
[16:28:32.670] Timer tick, asking Buildbucket for the build status
[16:28:32.747] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:32.747] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:22:0) after 1m0s
[16:29:32.772] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:22:0)
[16:29:32.772] Timer tick, asking Buildbucket for the build status
[16:29:32.940] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:32.940] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:23:0) after 1m0s
[16:30:32.999] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:23:0)
[16:30:32.999] Timer tick, asking Buildbucket for the build status
[16:30:33.062] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:33.062] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:24:0) after 1m0s
[16:31:33.148] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:24:0)
[16:31:33.148] Timer tick, asking Buildbucket for the build status
[16:31:33.721] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:33.721] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:25:0) after 1m0s
[16:32:33.749] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:25:0)
[16:32:33.749] Timer tick, asking Buildbucket for the build status
[16:32:33.865] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:33.865] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:26:0) after 1m0s
[16:33:33.929] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:26:0)
[16:33:33.929] Timer tick, asking Buildbucket for the build status
[16:33:34.001] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:34.001] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:27:0) after 1m0s
[16:34:34.025] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:27:0)
[16:34:34.025] Timer tick, asking Buildbucket for the build status
[16:34:34.147] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:34.147] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:28:0) after 1m0s
[16:35:34.206] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:28:0)
[16:35:34.206] Timer tick, asking Buildbucket for the build status
[16:35:34.610] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:34.610] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:29:0) after 1m0s
[16:36:34.712] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:29:0)
[16:36:34.712] Timer tick, asking Buildbucket for the build status
[16:36:34.791] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:34.791] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:30:0) after 1m0s
[16:37:34.921] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:30:0)
[16:37:34.921] Timer tick, asking Buildbucket for the build status
[16:37:35.155] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:35.155] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:31:0) after 1m0s
[16:38:35.163] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:31:0)
[16:38:35.163] Timer tick, asking Buildbucket for the build status
[16:38:35.633] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:35.633] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:32:0) after 1m0s
[16:39:35.750] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:32:0)
[16:39:35.750] Timer tick, asking Buildbucket for the build status
[16:39:35.886] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:35.886] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:33:0) after 1m0s
[16:40:35.944] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:33:0)
[16:40:35.944] Timer tick, asking Buildbucket for the build status
[16:40:36.027] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:36.027] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:34:0) after 1m0s
[16:41:36.179] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:34:0)
[16:41:36.179] Timer tick, asking Buildbucket for the build status
[16:41:36.273] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:36.273] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:35:0) after 1m0s
[16:42:36.343] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:35:0)
[16:42:36.343] Timer tick, asking Buildbucket for the build status
[16:42:36.415] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:36.415] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:36:0) after 1m0s
[16:43:36.456] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:36:0)
[16:43:36.456] Timer tick, asking Buildbucket for the build status
[16:43:36.552] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:36.552] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:37:0) after 1m0s
[16:44:36.622] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:37:0)
[16:44:36.622] Timer tick, asking Buildbucket for the build status
[16:44:36.736] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:36.736] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:38:0) after 1m0s
[16:45:36.813] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:38:0)
[16:45:36.813] Timer tick, asking Buildbucket for the build status
[16:45:36.928] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:36.928] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:39:0) after 1m0s
[16:46:36.950] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:39:0)
[16:46:36.950] Timer tick, asking Buildbucket for the build status
[16:46:37.020] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:37.020] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:40:0) after 1m0s
[16:47:37.056] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:40:0)
[16:47:37.056] Timer tick, asking Buildbucket for the build status
[16:47:37.113] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:37.113] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:41:0) after 1m0s
[16:48:37.182] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:41:0)
[16:48:37.182] Timer tick, asking Buildbucket for the build status
[16:48:37.315] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:37.315] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:42:0) after 1m0s
[16:49:37.396] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:42:0)
[16:49:37.396] Timer tick, asking Buildbucket for the build status
[16:49:37.508] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:37.508] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:43:0) after 1m0s
[16:50:37.555] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:43:0)
[16:50:37.555] Timer tick, asking Buildbucket for the build status
[16:50:37.768] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:37.768] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:44:0) after 1m0s
[16:51:37.837] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:44:0)
[16:51:37.837] Timer tick, asking Buildbucket for the build status
[16:51:38.058] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:38.059] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:45:0) after 1m0s
[16:52:38.186] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:45:0)
[16:52:38.186] Timer tick, asking Buildbucket for the build status
[16:52:38.311] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:38.311] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:46:0) after 1m0s
[16:53:38.351] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:46:0)
[16:53:38.351] Timer tick, asking Buildbucket for the build status
[16:53:38.601] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:38.601] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:47:0) after 1m0s
[16:54:38.657] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:47:0)
[16:54:38.657] Timer tick, asking Buildbucket for the build status
[16:54:38.749] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:38.749] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:48:0) after 1m0s
[16:55:38.797] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:48:0)
[16:55:38.797] Timer tick, asking Buildbucket for the build status
[16:55:39.302] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:39.302] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:49:0) after 1m0s
[16:56:39.382] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:49:0)
[16:56:39.382] Timer tick, asking Buildbucket for the build status
[16:56:39.618] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:39.618] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:50:0) after 1m0s
[16:57:39.639] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:50:0)
[16:57:39.639] Timer tick, asking Buildbucket for the build status
[16:57:39.727] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:39.727] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:51:0) after 1m0s
[16:58:39.783] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:51:0)
[16:58:39.783] Timer tick, asking Buildbucket for the build status
[16:58:40.033] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:40.033] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:52:0) after 1m0s
[16:59:40.057] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:52:0)
[16:59:40.057] Timer tick, asking Buildbucket for the build status
[16:59:40.216] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:40.216] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:53:0) after 1m0s
[17:00:40.239] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:53:0)
[17:00:40.239] Timer tick, asking Buildbucket for the build status
[17:00:40.320] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:40.320] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:54:0) after 1m0s
[17:01:40.415] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:54:0)
[17:01:40.415] Timer tick, asking Buildbucket for the build status
[17:01:40.676] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:40.676] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:55:0) after 1m0s
[17:02:40.699] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:55:0)
[17:02:40.699] Timer tick, asking Buildbucket for the build status
[17:02:40.968] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:40.968] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:56:0) after 1m0s
[17:03:41.043] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:56:0)
[17:03:41.043] Timer tick, asking Buildbucket for the build status
[17:03:41.444] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:41.444] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:57:0) after 1m0s
[17:04:41.536] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:57:0)
[17:04:41.536] Timer tick, asking Buildbucket for the build status
[17:04:41.805] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:41.805] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:58:0) after 1m0s
[17:05:41.832] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:58:0)
[17:05:41.832] Timer tick, asking Buildbucket for the build status
[17:05:42.132] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:42.132] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:59:0) after 1m0s
[17:06:42.159] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:59:0)
[17:06:42.159] Timer tick, asking Buildbucket for the build status
[17:06:42.544] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:42.544] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:60:0) after 1m0s
[17:07:42.588] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:60:0)
[17:07:42.588] Timer tick, asking Buildbucket for the build status
[17:07:42.993] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:42.993] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:61:0) after 1m0s
[17:08:43.023] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:61:0)
[17:08:43.023] Timer tick, asking Buildbucket for the build status
[17:08:43.216] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:43.216] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:62:0) after 1m0s
[17:09:43.243] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:62:0)
[17:09:43.243] Timer tick, asking Buildbucket for the build status
[17:09:43.841] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:43.841] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:63:0) after 1m0s
[17:10:43.859] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:63:0)
[17:10:43.859] Timer tick, asking Buildbucket for the build status
[17:10:44.007] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:44.007] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:64:0) after 1m0s
[17:11:44.033] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:64:0)
[17:11:44.033] Timer tick, asking Buildbucket for the build status
[17:11:44.176] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:44.176] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:65:0) after 1m0s
[17:12:44.208] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:65:0)
[17:12:44.208] Timer tick, asking Buildbucket for the build status
[17:12:44.493] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:44.493] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:66:0) after 1m0s
[17:13:44.507] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:66:0)
[17:13:44.507] Timer tick, asking Buildbucket for the build status
[17:13:44.573] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:44.573] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:67:0) after 1m0s
[17:14:44.599] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:67:0)
[17:14:44.599] Timer tick, asking Buildbucket for the build status
[17:14:44.736] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:44.736] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:68:0) after 1m0s
[17:15:44.761] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:68:0)
[17:15:44.761] Timer tick, asking Buildbucket for the build status
[17:15:45.006] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:45.006] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:69:0) after 1m0s
[17:16:45.099] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:69:0)
[17:16:45.099] Timer tick, asking Buildbucket for the build status
[17:16:45.341] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:45.341] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:70:0) after 1m0s
[17:17:45.412] Handling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:70:0)
[17:17:45.412] Timer tick, asking Buildbucket for the build status
[17:17:45.612] Build 8858839449215401648: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:45.612] Scheduling timer "check-buildbucket-build-status" (chromium/android-bfcache-rel:9024269511390600544:71:0) after 1m0s
[17:17:51.811] Received PubSub notification, asking Buildbucket for the build status
[17:17:51.884] Build 8858839449215401648: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:17:51.884] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1609953470536821",
"created_by": "project:chromium",
"created_ts": "1609949366324888",
"id": "8858839449215401648",
"parameters_json": "{\"builder_name\": \"android-bfcache-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f19e613c8658e4d123d02cbc3eb9cadd832c4821\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 150.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"f19e613c8658e4d123d02cbc3eb9cadd832c4821\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-206-70zj\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.android\", \"buildername\": \"android-bfcache-rel\", \"buildnumber\": 7126, \"got_angle_revision\": \"8ef860714479776778ae652047f86b383854eaf8\", \"got_dawn_revision\": \"a84acc4fcca80d80c379e3b02d8dc0f7b102ba9d\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"f19e613c8658e4d123d02cbc3eb9cadd832c4821\", \"got_revision_cp\": \"refs/heads/master@{#840621}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"b9e179f1f239bd1da8fffbd997a41095345d1f6a\", \"got_v8_revision\": \"8bf25fa0cda754dd0f8e0f8b7c9f8854eabfe72d\", \"got_v8_revision_cp\": \"refs/heads/8.9.213@{#1}\", \"got_webrtc_revision\": \"fc6b87192db6d5e4fd6997814f00b6792cf16180\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32904}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.android:android-bfcache-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f19e613c8658e4d123d02cbc3eb9cadd832c4821\", \"swarm_hashes_refs/heads/master(at){#840621}_without_patch\": {\"android_browsertests\": \"ef97f846a11f6be43e0214ee7d30cf1be0111cc2\", \"chrome_public_test_apk\": \"e1bc4234b18df9387a9eeb570e59542ab4dbf6c0\", \"content_browsertests\": \"23a5c4347fd7196f79f66db98756a9bd4bc03d42\", \"content_shell_test_apk\": \"2ea9638ad070fddbd3ef1ba734c17eb3d74b1ae9\", \"content_unittests\": \"898544da25f32818f8a63eef602ce855b36ef315\", \"unit_tests\": \"37d5836894b2feb296d452ef081f230d0f309521\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_55253def7cf185b290c24a7d0b74f783205e145476ba9938944ce33e13edac5f_v2\", \"builder_befd2764f389ab60d591810dc88de7e503142ac60c144228b6731720c84dd23a_v2\", \"builder_c18d585c17603559d8d68e3de2c6e12032537b56b99cb619aa84174b37006d15_v2\", \"builder_e47700e9abf1b62c06bb1feb4592b8e752d42e12f6359b4e93db4847c43379d6_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-206-70zj\"], \"image\": [\"chrome-xenial-20122700-a13233ce10d\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1609949498919263",
"status": "COMPLETED",
"status_changed_ts": "1609953471197790",
"tags": [
"build_address:luci.chromium.ci/android-bfcache-rel/7126",
"builder:android-bfcache-rel",
"buildset:commit/git/f19e613c8658e4d123d02cbc3eb9cadd832c4821",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f19e613c8658e4d123d02cbc3eb9cadd832c4821",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024269511390600544",
"scheduler_job_id:chromium/android-bfcache-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8858839449215401648/+/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:50f14752e59f8f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1609953471208774",
"url": "https://ci.chromium.org/b/8858839449215401648",
"utcnow_ts": "1609953471875854"
}
}
[17:17:51.884] Invocation finished in 1h8m27.018567227s with status SUCCEEDED