[21:50:21.180] New invocation is queued and will start shortly
[21:50:22.891] Starting the invocation (attempt 1)
[21:50:22.941] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:50:22.941] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:50:22.941] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9052967299760257296",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f713fd81ba82d62a12adc05efd72c52a62547444\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9052967299760257296",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/f713fd81ba82d62a12adc05efd72c52a62547444",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f713fd81ba82d62a12adc05efd72c52a62547444",
"gitiles_ref:refs/heads/master"
]
}
[21:50:24.197] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1582581023095527",
"id": "8887537237085826208",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f713fd81ba82d62a12adc05efd72c52a62547444\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582581023893884",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168980",
"builder:Linux Builder (dbg)",
"buildset:commit/git/f713fd81ba82d62a12adc05efd72c52a62547444",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f713fd81ba82d62a12adc05efd72c52a62547444",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9052967299760257296",
"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/8887537237085826208/+/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": "1582581023894050",
"url": "https://ci.chromium.org/b/8887537237085826208",
"utcnow_ts": "1582581024186687"
}
}
[21:50:24.197] Task URL: https://ci.chromium.org/b/8887537237085826208
[21:50:24.197] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:2:0) after 1m0s
[21:51:24.254] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:2:0)
[21:51:24.254] Timer tick, asking Buildbucket for the build status
[21:51:24.407] Build 8887537237085826208: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:51:24.407] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:3:0) after 1m0s
[21:52:24.497] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:3:0)
[21:52:24.497] Timer tick, asking Buildbucket for the build status
[21:52:24.652] Build 8887537237085826208: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:52:24.652] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:4:0) after 1m0s
[21:53:24.878] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:4:0)
[21:53:24.878] Timer tick, asking Buildbucket for the build status
[21:53:25.198] Build 8887537237085826208: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:53:25.198] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:5:0) after 1m0s
[21:54:25.203] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:5:0)
[21:54:25.203] Timer tick, asking Buildbucket for the build status
[21:54:25.527] Build 8887537237085826208: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:54:25.527] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:6:0) after 1m0s
[21:55:03.225] Received PubSub notification, asking Buildbucket for the build status
[21:55:03.369] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:25.668] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:6:0)
[21:55:25.668] Timer tick, asking Buildbucket for the build status
[21:55:25.805] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:25.805] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:8:0) after 1m0s
[21:56:25.974] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:8:0)
[21:56:25.975] Timer tick, asking Buildbucket for the build status
[21:56:26.284] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:26.284] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:9:0) after 1m0s
[21:57:26.499] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:9:0)
[21:57:26.499] Timer tick, asking Buildbucket for the build status
[21:57:26.652] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:26.652] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:10:0) after 1m0s
[21:58:26.715] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:10:0)
[21:58:26.715] Timer tick, asking Buildbucket for the build status
[21:58:26.842] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:26.842] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:11:0) after 1m0s
[21:59:26.937] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:11:0)
[21:59:26.937] Timer tick, asking Buildbucket for the build status
[21:59:27.043] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:27.043] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:12:0) after 1m0s
[22:00:27.171] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:12:0)
[22:00:27.171] Timer tick, asking Buildbucket for the build status
[22:00:27.377] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:27.377] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:13:0) after 1m0s
[22:01:27.448] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:13:0)
[22:01:27.448] Timer tick, asking Buildbucket for the build status
[22:01:27.788] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:27.788] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:14:0) after 1m0s
[22:02:27.990] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:14:0)
[22:02:27.990] Timer tick, asking Buildbucket for the build status
[22:02:28.234] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:28.234] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:15:0) after 1m0s
[22:03:28.330] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:15:0)
[22:03:28.330] Timer tick, asking Buildbucket for the build status
[22:03:28.501] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:28.501] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:16:0) after 1m0s
[22:04:28.619] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:16:0)
[22:04:28.619] Timer tick, asking Buildbucket for the build status
[22:04:29.005] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:29.005] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:17:0) after 1m0s
[22:05:29.233] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:17:0)
[22:05:29.233] Timer tick, asking Buildbucket for the build status
[22:05:29.360] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:29.360] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:18:0) after 1m0s
[22:06:29.788] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:18:0)
[22:06:29.788] Timer tick, asking Buildbucket for the build status
[22:06:30.618] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:30.618] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:19:0) after 1m0s
[22:07:30.873] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:19:0)
[22:07:30.873] Timer tick, asking Buildbucket for the build status
[22:07:31.202] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:31.202] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:20:0) after 1m0s
[22:08:31.193] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:20:0)
[22:08:31.193] Timer tick, asking Buildbucket for the build status
[22:08:32.576] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:32.576] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:21:0) after 1m0s
[22:09:32.685] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:21:0)
[22:09:32.685] Timer tick, asking Buildbucket for the build status
[22:09:33.231] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:33.231] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:22:0) after 1m0s
[22:10:33.315] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:22:0)
[22:10:33.315] Timer tick, asking Buildbucket for the build status
[22:10:33.428] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:33.428] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:23:0) after 1m0s
[22:11:33.465] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:23:0)
[22:11:33.465] Timer tick, asking Buildbucket for the build status
[22:11:34.770] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:34.770] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:24:0) after 1m0s
[22:12:34.954] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:24:0)
[22:12:34.954] Timer tick, asking Buildbucket for the build status
[22:12:35.330] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:35.330] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:25:0) after 1m0s
[22:13:35.384] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:25:0)
[22:13:35.384] Timer tick, asking Buildbucket for the build status
[22:13:35.496] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:35.496] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:26:0) after 1m0s
[22:14:35.594] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:26:0)
[22:14:35.594] Timer tick, asking Buildbucket for the build status
[22:14:35.705] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:35.705] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:27:0) after 1m0s
[22:15:35.827] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:27:0)
[22:15:35.827] Timer tick, asking Buildbucket for the build status
[22:15:36.005] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:36.005] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:28:0) after 1m0s
[22:16:36.135] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:28:0)
[22:16:36.135] Timer tick, asking Buildbucket for the build status
[22:16:36.963] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:36.963] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:29:0) after 1m0s
[22:17:37.077] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:29:0)
[22:17:37.077] Timer tick, asking Buildbucket for the build status
[22:17:37.423] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:37.423] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:30:0) after 1m0s
[22:18:37.461] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:30:0)
[22:18:37.461] Timer tick, asking Buildbucket for the build status
[22:18:37.707] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:37.707] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:31:0) after 1m0s
[22:19:37.729] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:31:0)
[22:19:37.729] Timer tick, asking Buildbucket for the build status
[22:19:37.977] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:37.977] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:32:0) after 1m0s
[22:20:37.994] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:32:0)
[22:20:37.994] Timer tick, asking Buildbucket for the build status
[22:20:38.239] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:38.239] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:33:0) after 1m0s
[22:21:38.277] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:33:0)
[22:21:38.277] Timer tick, asking Buildbucket for the build status
[22:21:38.457] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:38.457] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:34:0) after 1m0s
[22:22:38.479] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:34:0)
[22:22:38.479] Timer tick, asking Buildbucket for the build status
[22:22:38.619] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:38.619] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:35:0) after 1m0s
[22:23:38.693] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:35:0)
[22:23:38.693] Timer tick, asking Buildbucket for the build status
[22:23:38.869] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:38.869] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:36:0) after 1m0s
[22:24:38.886] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:36:0)
[22:24:38.886] Timer tick, asking Buildbucket for the build status
[22:24:39.063] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:39.063] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:37:0) after 1m0s
[22:25:39.112] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:37:0)
[22:25:39.112] Timer tick, asking Buildbucket for the build status
[22:25:39.352] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:39.352] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:38:0) after 1m0s
[22:26:39.378] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:38:0)
[22:26:39.378] Timer tick, asking Buildbucket for the build status
[22:26:39.479] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:39.480] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:39:0) after 1m0s
[22:27:39.526] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:39:0)
[22:27:39.526] Timer tick, asking Buildbucket for the build status
[22:27:39.669] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:39.669] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:40:0) after 1m0s
[22:28:39.738] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:40:0)
[22:28:39.738] Timer tick, asking Buildbucket for the build status
[22:28:40.163] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:40.163] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:41:0) after 1m0s
[22:29:40.166] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:41:0)
[22:29:40.166] Timer tick, asking Buildbucket for the build status
[22:29:40.613] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:40.613] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:42:0) after 1m0s
[22:30:40.682] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:42:0)
[22:30:40.682] Timer tick, asking Buildbucket for the build status
[22:30:40.908] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:40.908] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:43:0) after 1m0s
[22:31:40.981] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:43:0)
[22:31:40.981] Timer tick, asking Buildbucket for the build status
[22:31:42.459] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:42.459] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:44:0) after 1m0s
[22:32:42.518] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:44:0)
[22:32:42.518] Timer tick, asking Buildbucket for the build status
[22:32:42.669] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:42.669] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:45:0) after 1m0s
[22:33:42.714] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:45:0)
[22:33:42.714] Timer tick, asking Buildbucket for the build status
[22:33:43.163] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:43.163] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:46:0) after 1m0s
[22:34:43.351] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:46:0)
[22:34:43.351] Timer tick, asking Buildbucket for the build status
[22:34:43.538] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:43.538] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:47:0) after 1m0s
[22:35:43.562] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:47:0)
[22:35:43.562] Timer tick, asking Buildbucket for the build status
[22:35:43.815] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:43.815] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:48:0) after 1m0s
[22:36:43.852] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:48:0)
[22:36:43.852] Timer tick, asking Buildbucket for the build status
[22:36:44.242] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:44.242] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:49:0) after 1m0s
[22:37:44.316] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:49:0)
[22:37:44.316] Timer tick, asking Buildbucket for the build status
[22:37:44.485] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:44.485] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:50:0) after 1m0s
[22:38:44.648] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:50:0)
[22:38:44.648] Timer tick, asking Buildbucket for the build status
[22:38:44.784] Build 8887537237085826208: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:44.784] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052967299760257296:51:0) after 1m0s
[22:39:43.845] Received PubSub notification, asking Buildbucket for the build status
[22:39:43.911] Build 8887537237085826208: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:39:43.911] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1582583983039252",
"created_by": "project:chromium",
"created_ts": "1582581023095527",
"id": "8887537237085826208",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f713fd81ba82d62a12adc05efd72c52a62547444\"}}",
"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-1-ik2r\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168980, \"got_angle_revision\": \"c7f63a01d34473a95514661841a20d19668356a8\", \"got_dawn_revision\": \"1249ada986fc62a95439b8c8ec805fd3dff1e46d\", \"got_nacl_revision\": \"530e1f0dae34020864c81e5cd52be54d9c656690\", \"got_revision\": \"f713fd81ba82d62a12adc05efd72c52a62547444\", \"got_revision_cp\": \"refs/heads/master@{#744078}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"214ec337a0b2745e6c41fbb3d562fa517122812f\", \"got_v8_revision\": \"f6d87d0ff881338e5998a4c7177490292619d55b\", \"got_v8_revision_cp\": \"refs/heads/8.2.159@{#1}\", \"got_webrtc_revision\": \"1dea1ea412021819bddbc9400dcca7d9dd2eba74\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30581}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f713fd81ba82d62a12adc05efd72c52a62547444\", \"swarm_hashes_refs/heads/master(at){#744078}_without_patch\": {\"accessibility_unittests\": \"8cd2772746626c2972983c77eea58a8faf243452\", \"angle_unittests\": \"45b50d88f9943203a1ddf1204da60714a929288c\", \"app_shell_unittests\": \"a26e79f8ddfe4620eb11fbb51940d82320573023\", \"aura_unittests\": \"251bb42e620774b5017d7c1a3d8436f6e646b972\", \"base_unittests\": \"19b4b28feb0fcdbfa0219fa1cf1825dd8311660e\", \"base_util_unittests\": \"d895d1151507c3ca3f59beb775e386b2fc179def\", \"blink_common_unittests\": \"f5be602a956226f49b24fbea6ebc3e3758bed8d5\", \"blink_fuzzer_unittests\": \"dc87dcafb02124bbaeda65b2c5e9bdaf47fbe3fc\", \"blink_heap_unittests\": \"defc45d85b04e0e91378945a443d4fd9c41a3149\", \"blink_platform_unittests\": \"615161c74d3820a40324e498311849c6fadd5aad\", \"blink_python_tests\": \"005a52d3ce99cd67b1596976fffd567cf6b477bb\", \"blink_unittests\": \"40e2572e7ba376ced85844a73a2bf6303f8e45cd\", \"blink_web_tests\": \"d06c8aa87e035f38e0d5a19abad23d243f1cfc4b\", \"boringssl_crypto_tests\": \"97a3dc1c99b47c2115076ba7f56fdd51ffd8670f\", \"boringssl_ssl_tests\": \"3599e53644d40bf11c32ef4dffd734a12566622c\", \"browser_tests\": \"8e5812c6ae13b425fb231cd5e8d71328d99c1d3d\", \"cacheinvalidation_unittests\": \"82a239912102b4c88536d7960e969be6ef0de62d\", \"capture_unittests\": \"6639ad1c8c1586cfeb4173fb1242b73b65088141\", \"cast_unittests\": \"181c4a1a36b4ea549ea8ee0859a1714613ac7f55\", \"cc_unittests\": \"ae1abf08b07ff4c0cd3b6225c6cc18155b2b9e74\", \"chrome_app_unittests\": \"038302606461a8a554d29ffa2eba3c0df205f4ab\", \"chromedriver_unittests\": \"e5b64fe37b1c1f298ee087ef08b83fecbc0b0372\", \"components_browsertests\": \"42158f4c468029df15775ba825643931516b65fa\", \"components_unittests\": \"5c3718973a94ac1a9f95f4f3941c0dbf47bed593\", \"compositor_unittests\": \"b8736582707368ee0feb16dcc2163df12e07cb9e\", \"content_browsertests\": \"faa46ea5816ef4f160eedd33d7c8585dd3b5437e\", \"content_shell_crash_test\": \"8fe6e57f47139ad209302280ec3dde9148f27558\", \"content_unittests\": \"eb6fd0a6d3f1de873198e0b1a6aa54d11fd2dae8\", \"crashpad_tests\": \"513371200d1fd1f76a974b8677077fc1a71ee84a\", \"cronet_tests\": \"72c44ab303f0ce620967c2f39f48d87491623978\", \"cronet_unittests\": \"2b35fa24a053d6d204531672f0c5f21507f281f4\", \"crypto_unittests\": \"fe193777f94212058d79754e61ece9d3455888e0\", \"dbus_unittests\": \"192e8dd969ad3641b742b993ee43af0430713284\", \"device_unittests\": \"a081d960b080046ec92503d0938b9bce7a90f587\", \"display_unittests\": \"c18f8b53055b9af9af4fab35205e1dc89f2c1516\", \"events_unittests\": \"fb15729b20d09924ba2c49b3988f18373a7c3e31\", \"extensions_browsertests\": \"96013b292a8a64823ac64a4eba666491c1c5b4e5\", \"extensions_unittests\": \"4595544ad98f10017fe83354639d8a6d80529638\", \"filesystem_service_unittests\": \"bed5870fc1aa60ef16230d398c069c0d1040586c\", \"flatbuffers_unittests\": \"fddfee5d9c5c5357203fa42dff8f3c357607bc22\", \"gcm_unit_tests\": \"58ea79bc3a780df213f630e4fcb94c307e6d7934\", \"gfx_unittests\": \"0494443e5dbb58fc450a5d126f62ce4d638bfb2c\", \"gin_unittests\": \"d52b7b7aaac9211e0a855880454f2ab52e10699b\", \"google_apis_unittests\": \"7aad631817e142445e853c86c8a51d4c002250b8\", \"gpu_unittests\": \"0ada1ce1859edea55ff9905e4cac99ad5a4d4233\", \"grit_python_unittests\": \"8d3af1ca6ce7611b3ef6d2516a78cd61f915c28b\", \"gwp_asan_unittests\": \"c2414da6c4aa47110ae5cbaf465b75ce4b64bec1\", \"headless_browsertests\": \"8e26ddd86001d8387533d6010cf567bc87beb02d\", \"headless_unittests\": \"23b88c3755f276d21547ee82d3c29899abc29c4b\", \"interactive_ui_tests\": \"5e92a7504b195b8ed2c71e79b1136e7ce6cd7374\", \"ipc_tests\": \"4a5e96b9b1eeae57de7172e2360d04f7f6f0aecc\", \"jingle_unittests\": \"768c46b0a9d8c446c1136d0569b8ee814334ba99\", \"latency_unittests\": \"a8392ef88a5d37e741d8d81751036387f0c7d83f\", \"libjingle_xmpp_unittests\": \"61ac6d9055af99695be197e179212d04b6d9a027\", \"media_blink_unittests\": \"c5a1f8cdfe17e48af855b5719435c58048602fa8\", \"media_service_unittests\": \"560c8e924104081280c4d45e0856aae4099ecd6f\", \"media_unittests\": \"86a4b070d20e1f2d00ec263c5cfc02d16b99f887\", \"message_center_unittests\": \"4c8ace0e096cc97d0d3b7a1634f6d020b5ce590b\", \"metrics_python_tests\": \"1c147adf21e8fc42b4dcefa41f42255896d74356\", \"midi_unittests\": \"e365025be7e7f152bf1b89b7f88dc1d0e8410b9a\", \"mojo_core_unittests\": \"f0e733e6dc289fe1405042229a47ffd627f39f3f\", \"mojo_unittests\": \"640279652616d2251b4b2c49b7fa73b50dc4d11d\", \"nacl_helper_nonsfi_unittests\": \"d7398ffa7b105f8f2a3c08e277fc16e8d3481819\", \"nacl_loader_unittests\": \"55f83f4bb0c68f172952696a18cbd43f8e71e8a3\", \"native_theme_unittests\": \"dc2a5e55f83bf8e733698f41f721ebeed61acf3a\", \"net_unittests\": \"26a4eef7cac1b2b225ebc8ada7dc766bffa937f4\", \"pdf_unittests\": \"32b992443180a0083b4fc58d4525dbbaac74196d\", \"perfetto_unittests\": \"d89102aea53468593dcbfaa522e72c2b4ee15f59\", \"ppapi_unittests\": \"85f3f8156d79090f62b46b52e661615385536962\", \"printing_unittests\": \"ac4fa297c12b01493ea47f47e6ecba2fa726c51a\", \"remoting_unittests\": \"5d8df060a5349d43655d1f9711d5e6c77537f9ec\", \"sandbox_linux_unittests\": \"24e3fd127b75d5660502da043ed446016c65fd51\", \"service_manager_unittests\": \"97710b93fb72c8f87d5315f25596c3f550cd52d7\", \"services_unittests\": \"8edfb8ccdbe09883b9773a74d1a77924b54ae2f6\", \"shell_dialogs_unittests\": \"e21c5dc90d1be2b3891f3f5c8e2ad8ef94fa3de7\", \"skia_unittests\": \"2f9b69f50f60799fd8109965831725be95796fda\", \"snapshot_unittests\": \"597d2ee1d0469fb98538d3201614584a06c82345\", \"sql_unittests\": \"fa2aac2fc090935168c79000a737f4d6b6d7a4d7\", \"storage_unittests\": \"1e319728bad69bee205f1ab7c18a8faff14169be\", \"sync_integration_tests\": \"404e884096656ca7048da93053f8672010bcb4de\", \"telemetry_gpu_unittests\": \"a94b0213ebc4c6836ef82cbafdf4e50aac94cbf0\", \"telemetry_perf_unittests\": \"b44de5ec1648c444797b1c718614a72b5c71ce57\", \"telemetry_unittests\": \"168ddd3bc1258f89bc168b584225e57af1f4535b\", \"traffic_annotation_auditor_unittests\": \"55f7d6e95e3c2722ba466453ccf443a5423ec6e8\", \"ui_base_unittests\": \"2b0ee1832ce7b5ec04b2f7d861260b6faf7f8f5f\", \"ui_touch_selection_unittests\": \"71133788a9c9cfcc4e11285d6da65c1a24c1d6df\", \"unit_tests\": \"c961d6233426083a25275f9b2df6a3da80222015\", \"url_unittests\": \"3f1cd30a3a69bd1dd6aac9b54ea03b2f8ff29130\", \"views_perftests\": \"6724fe47b5ef9a95b54369a8ce55870bba27033f\", \"views_unittests\": \"4c7eeb8726eea0007a30fad95becfbaf0073814b\", \"viz_unittests\": \"23d86ec51a30430da247736ce2ab0d758a79a84b\", \"vr_common_unittests\": \"5e50f2a0c449c4a46e07f98dcead5f3c7fd11dc7\", \"vr_pixeltests\": \"27555f4816f4652d0a392240ad4695e52171a2a0\", \"webdriver_wpt_tests\": \"abc42e687f3dd7794868fbd615c6d4dd657d50f4\", \"weblayer_browsertests\": \"f904af076e9124e985c600af56e24eeb6cd3f3b5\", \"wm_unittests\": \"25d8c57da68f97269c4537560940633b6c3023dc\", \"wtf_unittests\": \"eda526ee88107a8847a7bbf4dfd55404838159f7\", \"xr_browser_tests\": \"48cfaf5d4ef2f0ce2d5ad5c635fb3b5c9bdb076e\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1d440c0fe768d5aee9ca6bdf9627b9225fc40a7a7d4789fbfcee2130c0ed2f6e_v2\", \"builder_3e75b41c9bcf12a22209c03b338cce1fafa12941d3074ed1e1ed2450e6b71594_v2\", \"builder_980e79f61a9eb10b3ecd5f710f08f0308a5299da9889db3d2b6a7010c019cc1f_v2\", \"builder_a3b3a06131dfa9924971b27d0acd4e18e80a2242b6d6ae41f94b770d7c996d6f_v2\", \"builder_b9373cfdcc5f43b5e07cc4931fc34027757a424c7183ae2b31ae65ec7718ca12_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-1-ik2r\"], \"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\": [\"4825-3d129d6\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1582581302017249",
"status": "COMPLETED",
"status_changed_ts": "1582583983413781",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168980",
"builder:Linux Builder (dbg)",
"buildset:commit/git/f713fd81ba82d62a12adc05efd72c52a62547444",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f713fd81ba82d62a12adc05efd72c52a62547444",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9052967299760257296",
"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/8887537237085826208/+/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:4a91ff8e5c532710",
"user_agent:luci-scheduler"
],
"updated_ts": "1582583983419052",
"url": "https://ci.chromium.org/b/8887537237085826208",
"utcnow_ts": "1582583983902462"
}
}
[22:39:43.911] Invocation finished in 49m22.749796802s with status SUCCEEDED