[20:35:06.336] New invocation is queued and will start shortly
[20:35:07.794] Starting the invocation (attempt 1)
[20:35:07.986] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:35:07.986] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:35:07.986] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9054240391449574208",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"864a8c3744c380c7cc64729f7e53235305c576e9\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9054240391449574208",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/864a8c3744c380c7cc64729f7e53235305c576e9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/864a8c3744c380c7cc64729f7e53235305c576e9",
"gitiles_ref:refs/heads/master"
]
}
[20:35:10.185] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1581366908750337",
"id": "8888810328249138576",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"864a8c3744c380c7cc64729f7e53235305c576e9\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1581366909804413",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168463",
"builder:Linux Builder (dbg)",
"buildset:commit/git/864a8c3744c380c7cc64729f7e53235305c576e9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/864a8c3744c380c7cc64729f7e53235305c576e9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054240391449574208",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8888810328249138576/+/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": "1581366909804596",
"url": "https://ci.chromium.org/b/8888810328249138576",
"utcnow_ts": "1581366910156262"
}
}
[20:35:10.185] Task URL: https://ci.chromium.org/b/8888810328249138576
[20:35:10.185] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:2:0) after 1m0s
[20:36:10.277] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:2:0)
[20:36:10.277] Timer tick, asking Buildbucket for the build status
[20:36:10.922] Build 8888810328249138576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:36:10.922] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:3:0) after 1m0s
[20:37:10.954] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:3:0)
[20:37:10.954] Timer tick, asking Buildbucket for the build status
[20:37:11.165] Build 8888810328249138576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:37:11.165] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:4:0) after 1m0s
[20:38:11.201] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:4:0)
[20:38:11.201] Timer tick, asking Buildbucket for the build status
[20:38:11.358] Build 8888810328249138576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:38:11.358] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:5:0) after 1m0s
[20:39:11.345] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:5:0)
[20:39:11.345] Timer tick, asking Buildbucket for the build status
[20:39:11.481] Build 8888810328249138576: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:39:11.481] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:6:0) after 1m0s
[20:40:07.278] Received PubSub notification, asking Buildbucket for the build status
[20:40:09.126] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:11.478] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:6:0)
[20:40:11.478] Timer tick, asking Buildbucket for the build status
[20:40:11.652] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:11.652] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:8:0) after 1m0s
[20:41:11.752] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:8:0)
[20:41:11.752] Timer tick, asking Buildbucket for the build status
[20:41:13.262] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:13.262] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:9:0) after 1m0s
[20:42:13.408] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:9:0)
[20:42:13.408] Timer tick, asking Buildbucket for the build status
[20:42:13.690] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:13.690] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:10:0) after 1m0s
[20:43:13.792] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:10:0)
[20:43:13.792] Timer tick, asking Buildbucket for the build status
[20:43:13.965] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:13.965] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:11:0) after 1m0s
[20:44:13.988] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:11:0)
[20:44:13.988] Timer tick, asking Buildbucket for the build status
[20:44:14.450] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:14.450] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:12:0) after 1m0s
[20:45:14.568] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:12:0)
[20:45:14.568] Timer tick, asking Buildbucket for the build status
[20:45:14.738] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:14.738] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:13:0) after 1m0s
[20:46:14.810] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:13:0)
[20:46:14.810] Timer tick, asking Buildbucket for the build status
[20:46:15.037] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:15.037] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:14:0) after 1m0s
[20:47:15.058] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:14:0)
[20:47:15.058] Timer tick, asking Buildbucket for the build status
[20:47:15.306] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:15.306] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:15:0) after 1m0s
[20:48:15.329] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:15:0)
[20:48:15.329] Timer tick, asking Buildbucket for the build status
[20:48:15.542] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:15.542] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:16:0) after 1m0s
[20:49:15.525] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:16:0)
[20:49:15.525] Timer tick, asking Buildbucket for the build status
[20:49:17.948] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:17.948] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:17:0) after 1m0s
[20:50:17.964] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:17:0)
[20:50:17.964] Timer tick, asking Buildbucket for the build status
[20:50:18.129] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:18.129] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:18:0) after 1m0s
[20:51:18.177] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:18:0)
[20:51:18.177] Timer tick, asking Buildbucket for the build status
[20:51:18.761] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:18.761] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:19:0) after 1m0s
[20:52:18.804] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:19:0)
[20:52:18.804] Timer tick, asking Buildbucket for the build status
[20:52:19.003] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:19.003] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:20:0) after 1m0s
[20:53:19.061] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:20:0)
[20:53:19.061] Timer tick, asking Buildbucket for the build status
[20:53:19.202] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:19.202] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:21:0) after 1m0s
[20:54:19.379] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:21:0)
[20:54:19.379] Timer tick, asking Buildbucket for the build status
[20:54:20.067] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:20.067] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:22:0) after 1m0s
[20:55:20.088] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:22:0)
[20:55:20.088] Timer tick, asking Buildbucket for the build status
[20:55:20.274] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:20.274] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:23:0) after 1m0s
[20:56:20.678] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:23:0)
[20:56:20.678] Timer tick, asking Buildbucket for the build status
[20:56:20.823] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:20.823] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:24:0) after 1m0s
[20:57:20.916] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:24:0)
[20:57:20.917] Timer tick, asking Buildbucket for the build status
[20:57:21.146] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:21.146] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:25:0) after 1m0s
[20:58:21.301] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:25:0)
[20:58:21.301] Timer tick, asking Buildbucket for the build status
[20:58:21.438] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:21.438] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:26:0) after 1m0s
[20:59:21.603] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:26:0)
[20:59:21.603] Timer tick, asking Buildbucket for the build status
[20:59:21.799] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:21.799] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:27:0) after 1m0s
[21:00:21.847] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:27:0)
[21:00:21.847] Timer tick, asking Buildbucket for the build status
[21:00:21.993] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:21.993] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:28:0) after 1m0s
[21:01:22.062] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:28:0)
[21:01:22.062] Timer tick, asking Buildbucket for the build status
[21:01:23.682] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:23.682] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:29:0) after 1m0s
[21:02:23.733] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:29:0)
[21:02:23.733] Timer tick, asking Buildbucket for the build status
[21:02:24.526] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:24.526] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:30:0) after 1m0s
[21:03:24.596] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:30:0)
[21:03:24.596] Timer tick, asking Buildbucket for the build status
[21:03:25.237] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:25.237] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:31:0) after 1m0s
[21:04:25.351] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:31:0)
[21:04:25.351] Timer tick, asking Buildbucket for the build status
[21:04:25.965] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:25.965] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:32:0) after 1m0s
[21:05:25.990] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:32:0)
[21:05:25.990] Timer tick, asking Buildbucket for the build status
[21:05:26.486] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:26.486] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:33:0) after 1m0s
[21:06:26.511] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:33:0)
[21:06:26.511] Timer tick, asking Buildbucket for the build status
[21:06:26.686] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:26.686] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:34:0) after 1m0s
[21:07:26.804] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:34:0)
[21:07:26.804] Timer tick, asking Buildbucket for the build status
[21:07:27.077] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:27.077] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:35:0) after 1m0s
[21:08:27.320] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:35:0)
[21:08:27.320] Timer tick, asking Buildbucket for the build status
[21:08:27.747] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:27.747] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:36:0) after 1m0s
[21:09:27.900] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:36:0)
[21:09:27.900] Timer tick, asking Buildbucket for the build status
[21:09:28.812] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:28.812] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:37:0) after 1m0s
[21:10:28.805] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:37:0)
[21:10:28.805] Timer tick, asking Buildbucket for the build status
[21:10:28.977] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:28.977] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:38:0) after 1m0s
[21:11:29.197] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:38:0)
[21:11:29.197] Timer tick, asking Buildbucket for the build status
[21:11:29.441] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:29.441] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:39:0) after 1m0s
[21:12:29.492] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:39:0)
[21:12:29.492] Timer tick, asking Buildbucket for the build status
[21:12:29.810] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:29.810] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:40:0) after 1m0s
[21:13:29.837] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:40:0)
[21:13:29.837] Timer tick, asking Buildbucket for the build status
[21:13:29.999] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:29.999] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:41:0) after 1m0s
[21:14:30.072] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:41:0)
[21:14:30.072] Timer tick, asking Buildbucket for the build status
[21:14:30.486] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:30.486] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:42:0) after 1m0s
[21:15:30.549] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:42:0)
[21:15:30.549] Timer tick, asking Buildbucket for the build status
[21:15:31.033] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:31.033] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:43:0) after 1m0s
[21:16:31.089] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:43:0)
[21:16:31.089] Timer tick, asking Buildbucket for the build status
[21:16:32.585] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:32.585] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:44:0) after 1m0s
[21:17:32.730] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:44:0)
[21:17:32.730] Timer tick, asking Buildbucket for the build status
[21:17:32.882] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:32.882] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:45:0) after 1m0s
[21:18:32.914] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:45:0)
[21:18:32.914] Timer tick, asking Buildbucket for the build status
[21:18:33.099] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:33.099] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:46:0) after 1m0s
[21:19:33.165] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:46:0)
[21:19:33.165] Timer tick, asking Buildbucket for the build status
[21:19:33.316] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:33.316] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:47:0) after 1m0s
[21:20:33.386] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:47:0)
[21:20:33.386] Timer tick, asking Buildbucket for the build status
[21:20:33.541] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:33.541] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:48:0) after 1m0s
[21:21:33.597] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:48:0)
[21:21:33.597] Timer tick, asking Buildbucket for the build status
[21:21:33.749] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:33.749] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:49:0) after 1m0s
[21:22:33.831] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:49:0)
[21:22:33.831] Timer tick, asking Buildbucket for the build status
[21:22:34.093] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:34.093] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:50:0) after 1m0s
[21:23:34.069] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:50:0)
[21:23:34.069] Timer tick, asking Buildbucket for the build status
[21:23:34.643] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:34.643] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:51:0) after 1m0s
[21:24:34.693] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:51:0)
[21:24:34.693] Timer tick, asking Buildbucket for the build status
[21:24:34.882] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:34.882] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:52:0) after 1m0s
[21:25:34.958] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:52:0)
[21:25:34.958] Timer tick, asking Buildbucket for the build status
[21:25:36.479] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:36.479] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:53:0) after 1m0s
[21:26:36.582] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:53:0)
[21:26:36.582] Timer tick, asking Buildbucket for the build status
[21:26:36.732] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:36.732] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:54:0) after 1m0s
[21:27:36.909] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:54:0)
[21:27:36.909] Timer tick, asking Buildbucket for the build status
[21:27:37.375] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:37.376] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:55:0) after 1m0s
[21:28:37.470] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:55:0)
[21:28:37.470] Timer tick, asking Buildbucket for the build status
[21:28:37.910] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:37.910] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:56:0) after 1m0s
[21:29:37.957] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:56:0)
[21:29:37.957] Timer tick, asking Buildbucket for the build status
[21:29:38.466] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:38.466] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:57:0) after 1m0s
[21:30:38.547] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:57:0)
[21:30:38.547] Timer tick, asking Buildbucket for the build status
[21:30:39.256] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:39.256] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:58:0) after 1m0s
[21:31:39.334] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:58:0)
[21:31:39.334] Timer tick, asking Buildbucket for the build status
[21:31:39.470] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:39.470] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:59:0) after 1m0s
[21:32:39.489] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:59:0)
[21:32:39.489] Timer tick, asking Buildbucket for the build status
[21:32:39.742] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:39.742] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:60:0) after 1m0s
[21:33:39.816] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:60:0)
[21:33:39.816] Timer tick, asking Buildbucket for the build status
[21:33:40.747] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:40.747] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:61:0) after 1m0s
[21:34:40.904] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:61:0)
[21:34:40.904] Timer tick, asking Buildbucket for the build status
[21:34:41.131] Build 8888810328249138576: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:41.131] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054240391449574208:62:0) after 1m0s
[21:34:43.455] Received PubSub notification, asking Buildbucket for the build status
[21:34:43.731] Build 8888810328249138576: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:34:43.731] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1581370482294709",
"created_by": "project:chromium",
"created_ts": "1581366908750337",
"id": "8888810328249138576",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"864a8c3744c380c7cc64729f7e53235305c576e9\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.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-47-zchw\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168463, \"got_angle_revision\": \"fc1e8503d88b8cfaa35610c382f0265be163f54a\", \"got_dawn_revision\": \"fb01a6024e4d8ba45047baa410783f7774e6cd9e\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"864a8c3744c380c7cc64729f7e53235305c576e9\", \"got_revision_cp\": \"refs/heads/master@{#739996}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"b3b2a4fc756f8f6dca980c75d785e06134b9d5f8\", \"got_v8_revision\": \"d0da7b4db4d335d91a374ec3be53199c482e7174\", \"got_v8_revision_cp\": \"refs/heads/8.2.56@{#1}\", \"got_webrtc_revision\": \"d4c3c3a454215b21f02d2a772a729b4c87d6b33e\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30486}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"864a8c3744c380c7cc64729f7e53235305c576e9\", \"swarm_hashes_refs/heads/master(at){#739996}_without_patch\": {\"accessibility_unittests\": \"38a0a09d6b63487f6fa87285b0373261d0944b1f\", \"angle_unittests\": \"9bed93b3ff097bf3354a9386be8318a6b2c771f9\", \"app_shell_unittests\": \"5bcf9ddcc2aac82e8f62cb35558bd84badc6187b\", \"aura_unittests\": \"57c187b39d93651c925c337e7bbf2335cb73302e\", \"base_unittests\": \"c645659e3688e926f7b78d798b64ea1a58150b46\", \"base_util_unittests\": \"39dc6513396b0eaa1e1c762ae38e9df8602b2578\", \"blink_common_unittests\": \"d4f7cc21c7983819d8ece7e0a5bb1787dc01d35b\", \"blink_fuzzer_unittests\": \"86e01cd967408ab081824753a4e4478f1f027084\", \"blink_heap_unittests\": \"70c39d48270466f312052f753518f6f922ea3648\", \"blink_platform_unittests\": \"3df5363f8887bf2c438c23891148780fc1ef0739\", \"blink_python_tests\": \"f1e2e05b29e55e87145863e8c4ddd0485edc685e\", \"blink_unittests\": \"05d3759a3d549c45ac11d09aec399ba9432f1047\", \"blink_web_tests\": \"caedf06003777dbfa51f000a2c61b84ff96914d4\", \"boringssl_crypto_tests\": \"b1df0a2d21f8b4d3ed8df59b18bb31e2aa536ea6\", \"boringssl_ssl_tests\": \"57845d3f63f48767eea1681b056c279b4f61e347\", \"browser_tests\": \"b36b42fed25571b50ff44d72fe73dfcae61c8196\", \"cacheinvalidation_unittests\": \"cd2b707d95251cd03159610ca3b6bdd7f8aa7337\", \"capture_unittests\": \"460705dbe0de089bb3fa343f989e69e38a9716f7\", \"cast_unittests\": \"81dfce26b60f3192f4112bb804c51e80ec2408d8\", \"cc_unittests\": \"a8511dcf969afd932ac20d96fbbe007b9a720bbf\", \"chrome_app_unittests\": \"81c33e27dbbfface5c5a6974b9555704d6585695\", \"chromedriver_unittests\": \"ed24ec8c2e6177b0b746c1c26fef33169ee600fb\", \"components_browsertests\": \"ac537bc65230e53ee74ed20ca44b57ff00b0d110\", \"components_unittests\": \"fd1267fcdda8a1d616140b2fd87f84db9d73217e\", \"compositor_unittests\": \"53bf2338b437287bb9de3e0f2533b62bef427d8f\", \"content_browsertests\": \"0000d433556450a217aa591dc7ff899a3b16bb21\", \"content_shell_crash_test\": \"6c328bef54c5dbe6db123ddac7b85ab074fbd2b1\", \"content_unittests\": \"8d19651050b5971240cd4bf823fb79242909a5c6\", \"crashpad_tests\": \"62b9398fe67834e0b6dec8c8a303821e2c605b28\", \"cronet_tests\": \"586dd00ead461c3d655af44ddb94e442b3d23bc2\", \"cronet_unittests\": \"ef05fa2c1cc2e44d1f65f6a06356577ccb297b01\", \"crypto_unittests\": \"09d04f9b9f7973d56a189b43d307759aff4269bf\", \"dbus_unittests\": \"fe55544f15cc914f9c93187578e9420cf8f18053\", \"device_unittests\": \"85e3544e6c37e9664b00e87692278ed31885c46c\", \"display_unittests\": \"276adae0dad093c6ade5eb8222ba7e120a00b4fc\", \"events_unittests\": \"f5c70c5f9b739be654d14d4388f7f004872783e0\", \"extensions_browsertests\": \"6c7063cdcf819051183cfb4070147b2260edf808\", \"extensions_unittests\": \"0c3c304e301895ae87fac7541ca7510bc6fae4e4\", \"filesystem_service_unittests\": \"d8750ceff7df048a0cd50114d2f2e4d480906e71\", \"flatbuffers_unittests\": \"e0b6595529551438a032f600cb9a377756ae2cbe\", \"gcm_unit_tests\": \"447448e795eae3a6897996207c8a7efc8565d893\", \"gfx_unittests\": \"938af28b22694d37c2642c2de8c849ded0a64ac9\", \"gin_unittests\": \"ce1b8cf0cce870cd798a34bcd3fec571ad56dc99\", \"google_apis_unittests\": \"81af9b9b991ec5e708c864b476dbc3021935ca6d\", \"gpu_unittests\": \"a3715eec97eb3673a0604be790d105b8f338334e\", \"grit_python_unittests\": \"89d7a141763e719d54e5ba4775fa526042c3bdf4\", \"gwp_asan_unittests\": \"6e69123770a5070445c8c32f36cae4b77f34a9a0\", \"headless_browsertests\": \"e05d816461e5fafd001d46761f759ee33a16e11b\", \"headless_unittests\": \"840b56eb1fdb50413c48d61b42ac7974aef60379\", \"interactive_ui_tests\": \"621ce5a7d64624c6d665a856fd9131faecfa6e83\", \"ipc_tests\": \"33dacb8e15b61f78c19c389a25cff20ec7f765d7\", \"jingle_unittests\": \"5b29984ad262348b4612d5b7dfb81fa0f8e8013d\", \"latency_unittests\": \"82c6262ae387fffe26ac25775339e02adc801aed\", \"libjingle_xmpp_unittests\": \"c898518c29cbb6136f23c01f81d97dba32b83efa\", \"media_blink_unittests\": \"2b26c00f64ec0a318e851a9e35c97b9e5a9f1631\", \"media_service_unittests\": \"5eb2571df9d729862d3a554297c0a4b6e8c09ad2\", \"media_unittests\": \"91045fae0c32fb9dc45cd43a50f3f0fa72a4679c\", \"message_center_unittests\": \"15863af2d53411f60a2873bc406164a63d88f9bb\", \"metrics_python_tests\": \"407a219090fdeea5121a09f42e05c012eb1a3266\", \"midi_unittests\": \"3364f0ab137283e2f45a71a67910d98246e66795\", \"mojo_core_unittests\": \"0ff015e8dbd61f3cf087fce35b99d5aa4aff4a7f\", \"mojo_unittests\": \"d593114d8c7eac01306fe120befa30c94d23c177\", \"nacl_helper_nonsfi_unittests\": \"55ecd3704611d76da1d7ea55a6742fe35feb3644\", \"nacl_loader_unittests\": \"be57fe25640576e2adce94a5cf8e1275cfcd9b2f\", \"native_theme_unittests\": \"a6cd47dd5b9dd9671a6a7fc94af24c698cf9b868\", \"net_unittests\": \"55d1908a6e6e1bc565d08596d7a07abc7bc2a34b\", \"pdf_unittests\": \"4388e7a907d3e301352839645de9cf36cc7ba89a\", \"perfetto_unittests\": \"63c72d750c6454c203152f4fd5ba82afb581befc\", \"ppapi_unittests\": \"1e3a24755a816cb31a8c7014f06fe3f88bcf23eb\", \"printing_unittests\": \"1ad704cdecb683a982f43bf8293740921f801d48\", \"remoting_unittests\": \"7bf58a25c7afe8ba819068c26bc9b95224e438c3\", \"sandbox_linux_unittests\": \"27addec9ba85f84b5b272ebc77d76854d6a042f3\", \"service_manager_unittests\": \"3ddc72578ad71a38e3f5c853b8bba840659730ab\", \"services_unittests\": \"5a216448fe149c19ee56665e2ce618daf563f4b3\", \"shell_dialogs_unittests\": \"6dd8f673e7580f50c67250386bff7bbc20afc044\", \"skia_unittests\": \"bf7676cb6f195246cf687f2ca9a155b92436ed80\", \"snapshot_unittests\": \"a9209aa94546bdd914dffbd67144dbb6cecda56b\", \"sql_unittests\": \"8bab093962a7af1ea55e0bfd1e7d0f8236e6cc3d\", \"storage_unittests\": \"f5ee01ed7f836f8a03f7a06188b677a8fe2061f0\", \"sync_integration_tests\": \"0574a67725ce558603c22858a5915c31423bef88\", \"telemetry_gpu_unittests\": \"87377138cc4202e86488338bcff66bd89fef954b\", \"telemetry_perf_unittests\": \"b4818ee4ceb2bcb8ab4dbe9286fc890f2b40aa5e\", \"telemetry_unittests\": \"7c8c374b809cc531d2fc7fd4ec44e332ae5013cb\", \"traffic_annotation_auditor_unittests\": \"4ec36cc891d9b5d81cdeee70d5ba503d90682294\", \"ui_base_unittests\": \"e2527c34db64f0c1fb6bd912e4020a4a7b8f8925\", \"ui_touch_selection_unittests\": \"5acea86d012b19a9213cb934fc7a20f6f6fd3062\", \"unit_tests\": \"f6b74ee56195ac98580b7757bae694ced569c797\", \"url_unittests\": \"86f3b6098dd3de3c782c788d431b5539046919c5\", \"views_perftests\": \"e4472445a77ccd9de3933af6bc53d58df9b8fe36\", \"views_unittests\": \"cdaec247ff094c257ff72cd04ed261040c452457\", \"viz_unittests\": \"86bb443e4280b2604f7e48bd86a4aa0f0b78a120\", \"vr_common_unittests\": \"9ff53c242bf4859308f0a3e1a6effbb50669b895\", \"vr_pixeltests\": \"b2a4a2b506bbbe34209d3aa470722980f083288e\", \"webdriver_wpt_tests\": \"7e17d029072a36c419692b87d3385e40f53dad52\", \"weblayer_browsertests\": \"882bb945f339554e2f057212020f9f269770b5e6\", \"wm_unittests\": \"44efacaae4c04af046de1c783a409a0145cc4642\", \"wtf_unittests\": \"a4162f678e9cea8e41990e72a5d3bc8e608311f8\", \"xr_browser_tests\": \"2dc4233757fa2050f98af86043e20385ec765932\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_361cbaa79261c75a92fc63a8f8c6fc117764238b3277286fa51086e6da3301df_v2\", \"builder_430de131691c92065dcde51cfe816220b2f886d2d859c4fe33bcc0b34c123c61_v2\", \"builder_93045653dd8238f9b5a16956c3abf0c7402036195ded7f89ac6ed546092c13e3_v2\", \"builder_ffe623326ac74578ce917ff55ef6cefa75094e5ad3ad5fe3409a729d79be647a_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-47-zchw\"], \"image\": [\"chrome-xenial-20012600-8e1a7e485b7\"], \"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\": [\"4812-ea7e8fc\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1581367206218000",
"status": "COMPLETED",
"status_changed_ts": "1581370482773184",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168463",
"builder:Linux Builder (dbg)",
"buildset:commit/git/864a8c3744c380c7cc64729f7e53235305c576e9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/864a8c3744c380c7cc64729f7e53235305c576e9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054240391449574208",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8888810328249138576/+/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:4a49a1a58736d410",
"user_agent:luci-scheduler"
],
"updated_ts": "1581370482777230",
"url": "https://ci.chromium.org/b/8888810328249138576",
"utcnow_ts": "1581370483721415"
}
}
[21:34:43.731] Invocation finished in 59m37.417445155s with status SUCCEEDED