[22:57:01.110] New invocation is queued and will start shortly
[22:57:03.089] Starting the invocation (attempt 1)
[22:57:03.128] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:57:03.128] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:57:03.129] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9057221162953384048",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a7653678f328ed14ec77995afa94c9df857bd3fa\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9057221162953384048",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/a7653678f328ed14ec77995afa94c9df857bd3fa",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a7653678f328ed14ec77995afa94c9df857bd3fa",
"gitiles_ref:refs/heads/master"
]
}
[22:57:06.159] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1578524223536317",
"id": "8891791099739506672",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a7653678f328ed14ec77995afa94c9df857bd3fa\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1578524225940401",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/167146",
"builder:Linux Builder (dbg)",
"buildset:commit/git/a7653678f328ed14ec77995afa94c9df857bd3fa",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a7653678f328ed14ec77995afa94c9df857bd3fa",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9057221162953384048",
"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/8891791099739506672/+/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": "1578524225941067",
"url": "https://ci.chromium.org/b/8891791099739506672",
"utcnow_ts": "1578524226143810"
}
}
[22:57:06.159] Task URL: https://ci.chromium.org/b/8891791099739506672
[22:57:06.159] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:2:0) after 1m0s
[22:58:06.257] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:2:0)
[22:58:06.257] Timer tick, asking Buildbucket for the build status
[22:58:06.444] Build 8891791099739506672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:58:06.444] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:3:0) after 1m0s
[22:59:06.466] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:3:0)
[22:59:06.466] Timer tick, asking Buildbucket for the build status
[22:59:06.636] Build 8891791099739506672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:59:06.636] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:4:0) after 1m0s
[23:00:06.682] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:4:0)
[23:00:06.682] Timer tick, asking Buildbucket for the build status
[23:00:07.081] Build 8891791099739506672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:00:07.081] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:5:0) after 1m0s
[23:01:07.118] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:5:0)
[23:01:07.118] Timer tick, asking Buildbucket for the build status
[23:01:07.292] Build 8891791099739506672: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:01:07.292] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:6:0) after 1m0s
[23:02:07.876] Received PubSub notification, asking Buildbucket for the build status
[23:02:08.682] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:09.663] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:6:0)
[23:02:09.663] Timer tick, asking Buildbucket for the build status
[23:02:10.128] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:10.128] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:8:0) after 1m0s
[23:03:10.693] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:8:0)
[23:03:10.693] Timer tick, asking Buildbucket for the build status
[23:03:10.922] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:10.922] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:9:0) after 1m0s
[23:04:11.131] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:9:0)
[23:04:11.131] Timer tick, asking Buildbucket for the build status
[23:04:11.672] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:11.672] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:10:0) after 1m0s
[23:05:11.744] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:10:0)
[23:05:11.744] Timer tick, asking Buildbucket for the build status
[23:05:11.988] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:11.988] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:11:0) after 1m0s
[23:06:12.052] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:11:0)
[23:06:12.052] Timer tick, asking Buildbucket for the build status
[23:06:12.522] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:12.522] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:12:0) after 1m0s
[23:07:12.701] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:12:0)
[23:07:12.701] Timer tick, asking Buildbucket for the build status
[23:07:12.947] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:12.947] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:13:0) after 1m0s
[23:08:13.184] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:13:0)
[23:08:13.184] Timer tick, asking Buildbucket for the build status
[23:08:13.969] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:13.969] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:14:0) after 1m0s
[23:09:14.039] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:14:0)
[23:09:14.039] Timer tick, asking Buildbucket for the build status
[23:09:14.940] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:14.940] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:15:0) after 1m0s
[23:10:14.998] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:15:0)
[23:10:14.998] Timer tick, asking Buildbucket for the build status
[23:10:15.244] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:15.244] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:16:0) after 1m0s
[23:11:15.323] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:16:0)
[23:11:15.323] Timer tick, asking Buildbucket for the build status
[23:11:16.125] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:16.125] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:17:0) after 1m0s
[23:12:16.159] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:17:0)
[23:12:16.159] Timer tick, asking Buildbucket for the build status
[23:12:16.959] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:16.959] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:18:0) after 1m0s
[23:13:17.007] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:18:0)
[23:13:17.007] Timer tick, asking Buildbucket for the build status
[23:13:17.209] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:17.209] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:19:0) after 1m0s
[23:14:17.316] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:19:0)
[23:14:17.316] Timer tick, asking Buildbucket for the build status
[23:14:17.542] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:17.542] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:20:0) after 1m0s
[23:15:17.596] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:20:0)
[23:15:17.596] Timer tick, asking Buildbucket for the build status
[23:15:17.865] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:17.865] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:21:0) after 1m0s
[23:16:17.855] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:21:0)
[23:16:17.855] Timer tick, asking Buildbucket for the build status
[23:16:18.315] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:18.315] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:22:0) after 1m0s
[23:17:18.380] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:22:0)
[23:17:18.380] Timer tick, asking Buildbucket for the build status
[23:17:19.280] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:19.280] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:23:0) after 1m0s
[23:18:19.313] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:23:0)
[23:18:19.313] Timer tick, asking Buildbucket for the build status
[23:18:20.056] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:20.058] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:24:0) after 1m0s
[23:19:20.248] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:24:0)
[23:19:20.248] Timer tick, asking Buildbucket for the build status
[23:19:20.441] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:20.441] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:25:0) after 1m0s
[23:20:20.628] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:25:0)
[23:20:20.628] Timer tick, asking Buildbucket for the build status
[23:20:21.117] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:21.117] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:26:0) after 1m0s
[23:21:21.212] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:26:0)
[23:21:21.212] Timer tick, asking Buildbucket for the build status
[23:21:21.710] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:21.710] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:27:0) after 1m0s
[23:22:21.801] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:27:0)
[23:22:21.801] Timer tick, asking Buildbucket for the build status
[23:22:23.706] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:23.706] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:28:0) after 1m0s
[23:23:24.223] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:28:0)
[23:23:24.223] Timer tick, asking Buildbucket for the build status
[23:23:24.432] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:24.432] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:29:0) after 1m0s
[23:24:24.510] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:29:0)
[23:24:24.510] Timer tick, asking Buildbucket for the build status
[23:24:24.819] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:24.819] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:30:0) after 1m0s
[23:25:25.051] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:30:0)
[23:25:25.051] Timer tick, asking Buildbucket for the build status
[23:25:25.266] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:25.266] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:31:0) after 1m0s
[23:26:25.436] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:31:0)
[23:26:25.436] Timer tick, asking Buildbucket for the build status
[23:26:26.503] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:26.503] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:32:0) after 1m0s
[23:27:26.650] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:32:0)
[23:27:26.650] Timer tick, asking Buildbucket for the build status
[23:27:27.067] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:27.067] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:33:0) after 1m0s
[23:28:27.107] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:33:0)
[23:28:27.107] Timer tick, asking Buildbucket for the build status
[23:28:27.580] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:27.580] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:34:0) after 1m0s
[23:29:27.652] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:34:0)
[23:29:27.652] Timer tick, asking Buildbucket for the build status
[23:29:28.495] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:28.495] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:35:0) after 1m0s
[23:30:28.555] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:35:0)
[23:30:28.555] Timer tick, asking Buildbucket for the build status
[23:30:29.098] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:29.098] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:36:0) after 1m0s
[23:31:29.230] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:36:0)
[23:31:29.230] Timer tick, asking Buildbucket for the build status
[23:31:29.568] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:29.568] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:37:0) after 1m0s
[23:32:29.663] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:37:0)
[23:32:29.663] Timer tick, asking Buildbucket for the build status
[23:32:29.890] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:29.890] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:38:0) after 1m0s
[23:33:29.931] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:38:0)
[23:33:29.931] Timer tick, asking Buildbucket for the build status
[23:33:30.649] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:30.649] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:39:0) after 1m0s
[23:34:30.685] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:39:0)
[23:34:30.685] Timer tick, asking Buildbucket for the build status
[23:34:31.162] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:31.162] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:40:0) after 1m0s
[23:35:31.205] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:40:0)
[23:35:31.205] Timer tick, asking Buildbucket for the build status
[23:35:33.690] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:33.690] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:41:0) after 1m0s
[23:36:33.775] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:41:0)
[23:36:33.775] Timer tick, asking Buildbucket for the build status
[23:36:34.138] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:34.138] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:42:0) after 1m0s
[23:37:34.116] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:42:0)
[23:37:34.116] Timer tick, asking Buildbucket for the build status
[23:37:34.355] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:34.355] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:43:0) after 1m0s
[23:38:34.394] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:43:0)
[23:38:34.394] Timer tick, asking Buildbucket for the build status
[23:38:34.598] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:34.598] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:44:0) after 1m0s
[23:39:34.656] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:44:0)
[23:39:34.656] Timer tick, asking Buildbucket for the build status
[23:39:35.064] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:35.064] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:45:0) after 1m0s
[23:40:35.109] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:45:0)
[23:40:35.109] Timer tick, asking Buildbucket for the build status
[23:40:35.356] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:35.356] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:46:0) after 1m0s
[23:41:35.446] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:46:0)
[23:41:35.446] Timer tick, asking Buildbucket for the build status
[23:41:35.800] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:35.800] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:47:0) after 1m0s
[23:42:35.900] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:47:0)
[23:42:35.900] Timer tick, asking Buildbucket for the build status
[23:42:36.315] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:36.315] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:48:0) after 1m0s
[23:43:36.522] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:48:0)
[23:43:36.522] Timer tick, asking Buildbucket for the build status
[23:43:36.727] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:36.727] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:49:0) after 1m0s
[23:44:36.710] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:49:0)
[23:44:36.710] Timer tick, asking Buildbucket for the build status
[23:44:37.361] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:37.361] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:50:0) after 1m0s
[23:45:37.487] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:50:0)
[23:45:37.487] Timer tick, asking Buildbucket for the build status
[23:45:37.651] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:37.651] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:51:0) after 1m0s
[23:46:37.676] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:51:0)
[23:46:37.676] Timer tick, asking Buildbucket for the build status
[23:46:38.263] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:38.263] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:52:0) after 1m0s
[23:47:38.296] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:52:0)
[23:47:38.296] Timer tick, asking Buildbucket for the build status
[23:47:38.617] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:38.617] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:53:0) after 1m0s
[23:48:38.649] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:53:0)
[23:48:38.649] Timer tick, asking Buildbucket for the build status
[23:48:38.914] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:38.914] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:54:0) after 1m0s
[23:49:38.967] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:54:0)
[23:49:38.967] Timer tick, asking Buildbucket for the build status
[23:49:39.140] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:39.140] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:55:0) after 1m0s
[23:50:39.164] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:55:0)
[23:50:39.164] Timer tick, asking Buildbucket for the build status
[23:50:40.309] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:40.309] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:56:0) after 1m0s
[23:51:40.296] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:56:0)
[23:51:40.297] Timer tick, asking Buildbucket for the build status
[23:51:41.146] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:41.147] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:57:0) after 1m0s
[23:52:41.190] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:57:0)
[23:52:41.190] Timer tick, asking Buildbucket for the build status
[23:52:41.395] Build 8891791099739506672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:41.395] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057221162953384048:58:0) after 1m0s
[23:53:39.317] Received PubSub notification, asking Buildbucket for the build status
[23:53:39.500] Build 8891791099739506672: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:53:39.500] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1578527618482253",
"created_by": "project:chromium",
"created_ts": "1578524223536317",
"id": "8891791099739506672",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a7653678f328ed14ec77995afa94c9df857bd3fa\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"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-31-yn20\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 167146, \"got_angle_revision\": \"99c274baf503ac6d7ece36c848bfcc1f1488e3f5\", \"got_dawn_revision\": \"9b2712456fe635ae460bd3bc4fbf29b78772e2e0\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"a7653678f328ed14ec77995afa94c9df857bd3fa\", \"got_revision_cp\": \"refs/heads/master@{#729529}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"c45e066fba2d8b6947fe1216c788f6ffbbb8a466\", \"got_v8_revision\": \"1f5e1e29dd98ab0f0ff1f64183ce5ace076aeb5f\", \"got_v8_revision_cp\": \"refs/heads/8.1.115@{#1}\", \"got_webrtc_revision\": \"28209b43f4a1e7048c01f9dfb56ab5a873849766\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30183}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a7653678f328ed14ec77995afa94c9df857bd3fa\", \"swarm_hashes_refs/heads/master(at){#729529}_without_patch\": {\"accessibility_unittests\": \"4e3467741eb455e8d0e3fe6e42e16ad89820431c\", \"angle_unittests\": \"d49c6a78907cbfa2326c20a0aeb8cdd2d6ffb845\", \"app_shell_unittests\": \"ccc36d53f46549a9a535eff293fce4386466ede2\", \"aura_unittests\": \"c226f4f34eed4583c3081be571023f86923a15e6\", \"base_unittests\": \"c21b683c6b6f2d110ab09b3a61d6df6f87cfd50a\", \"base_util_unittests\": \"0fe1363d7a79e844b0caa7eb498d5ff618e7e794\", \"blink_common_unittests\": \"bcaf78fd2f993e16c7a3671dd7ecf6101e144e25\", \"blink_fuzzer_unittests\": \"761a01449befe97caf846e0daf3382d94171919e\", \"blink_heap_unittests\": \"3b1e72aab039c63c3c79c29880659df5055a00a3\", \"blink_platform_unittests\": \"444e6498c40d514231d3430a8fdfbd74ce4c3e95\", \"blink_python_tests\": \"d373c08505addf1831f4b94c49ae80c09186429a\", \"blink_unittests\": \"2a6ce0c482d2369e04766e7561f7ca5bec16c966\", \"blink_web_tests\": \"36efcd06517504526bb7f3814530596a6e0cb1aa\", \"boringssl_crypto_tests\": \"e43a7b6b39e58ea11dd82787bc92a3b94fb0cfd5\", \"boringssl_ssl_tests\": \"8cad87061e4c9b7f155e100f6ee0792bd7076704\", \"browser_tests\": \"ac3215a844a6801b67e32407c2272afad43b195a\", \"cacheinvalidation_unittests\": \"742ea0fecdb582f690c4b7ef8a81242a0e7bdc65\", \"capture_unittests\": \"b472d067a660547f80cc88bf6fab93c53925ff0d\", \"cast_unittests\": \"6816d36814d9043645fc452e5b423141c2d64e55\", \"cc_unittests\": \"0040d245473f56ae4ef29358e2f2cbf3232cbd1b\", \"chrome_app_unittests\": \"12b4946ff07f40bf00fff7f3fa02030f9f7827c6\", \"chromedriver_unittests\": \"e51c587650a390176f3c844258fa5e17042897a7\", \"components_browsertests\": \"faf163474fdd82a84bf16f81a18e4dcebef9997b\", \"components_unittests\": \"680d4ee0dd3107a207e434d1c6b14b1adefbbf4a\", \"compositor_unittests\": \"4e3be6f76664a731bf91cf4c16e8cf493f94d9a3\", \"content_browsertests\": \"bfdce800b6fec9954144fdae2f1d2e13bb75750d\", \"content_shell_crash_test\": \"f2245aa516d61486bc05f5655db739b947c3e503\", \"content_unittests\": \"54366ce6e2acd1fd15ba23f92465dc29177c38e3\", \"crashpad_tests\": \"f6905448cf389d798ca28e60da64df527c7a82b3\", \"cronet_tests\": \"5bcbe535d6d903a5f58098acf23bfd4c9ce559f5\", \"cronet_unittests\": \"98bdb006ecb26a5d226ab575391b88be3a7d4522\", \"crypto_unittests\": \"e9744cc8b21a04112068b28b51d24589c5eba14d\", \"dbus_unittests\": \"9fe65a1c52faedb53d1fc18c32bb512747eb86cf\", \"device_unittests\": \"d634f758a5ac4b4b091bdc86bb40f6b215c254b7\", \"devtools_lint_check\": \"4e789253692dee7f1930245a284129dbc796c704\", \"devtools_type_check\": \"0d12eb60a2fecadb485ae41c2a1180fedddac7ea\", \"display_unittests\": \"e11c495fb3a0281b0a1e2edf1cc1dbdf8fb3b24c\", \"events_unittests\": \"fe7faaa76afadaf66e540ee5f6459df7fc123475\", \"extensions_browsertests\": \"95e600d74c1ceb918ac18a112707cabe8ff7cc3b\", \"extensions_unittests\": \"39e450e359213c2e212bb82f0479e85ab7e07564\", \"filesystem_service_unittests\": \"fdeaa5785044cfe4dfcfa1f526943403a3da5674\", \"flatbuffers_unittests\": \"034d1c63a8199a680c9bbf29b5866b1c851e28ee\", \"gcm_unit_tests\": \"807366698c669688fdb53883777ca5be96ac889e\", \"gfx_unittests\": \"554d62eff8f84c0a09440a8dfbfc408d8515a6b2\", \"gin_unittests\": \"a1783fbe35c734aa338fcb82258fab2951e4034c\", \"google_apis_unittests\": \"82d8aef49ef59c0356c2e9043cebad2aa5d4fc05\", \"gpu_unittests\": \"12c0cd65cb5f448b77dfb525a56703e97190207e\", \"grit_python_unittests\": \"5d2561226d0db6aa6e2a7322c9c06178bd9c6346\", \"gwp_asan_unittests\": \"1643423604050ea289c78b45a7b2f7ca4ff957a9\", \"headless_browsertests\": \"b219b7791361011af4720846e9de416a28b9673c\", \"headless_unittests\": \"9df3714615cdb6e054894768f43b7c3ff9f62ec2\", \"interactive_ui_tests\": \"0e6bd5d664e27af10dc7eaa79a165988280af2d9\", \"ipc_tests\": \"eb43b8bb2485885062b7e3f58f28dbe69134a219\", \"jingle_unittests\": \"12fb0c37fda9dc8da78098525e024a0ee57860c1\", \"latency_unittests\": \"7f15133d51db764b4120774287fffed1beb1a04b\", \"libjingle_xmpp_unittests\": \"eb899fa3dd36d1d0224d4d7c37bb64c1621160a9\", \"media_blink_unittests\": \"50ebbb16173362638fdc77e511673fd54e2da256\", \"media_service_unittests\": \"ec33fe36f2e71ad38d8acd8e563a4b21466de40d\", \"media_unittests\": \"441db06a209f85846d9a6924b7be2bb02d169e6c\", \"message_center_unittests\": \"588c5f6f076b66c826bf9adc0edae95e0613a249\", \"metrics_python_tests\": \"88dfb3aa132fe5a01f1deb1b1b9f7f519468cc31\", \"midi_unittests\": \"eb3ce4446ca4c50072f7f24d13b26567fb2c570b\", \"mojo_core_unittests\": \"9e710fe5bce0464be672d29ccb323cd2bab6af14\", \"mojo_unittests\": \"1ada09cac0b473c35b8a801ab44fe850ffe2ec83\", \"nacl_helper_nonsfi_unittests\": \"ffe4afde1ac13e0c5d7f0d764afd2fb13d5d0306\", \"nacl_loader_unittests\": \"dd0c6732a068bb0d042fce74544e42b8695ce1c3\", \"native_theme_unittests\": \"0536d5c84941a2e44566c7da894ea0a314bacfbf\", \"net_unittests\": \"2327c5640feb51b9208726c2a9738497d43ad236\", \"pdf_unittests\": \"bd4ff158a13de7028e5642861af85d79f0186935\", \"perfetto_unittests\": \"08fbcc6816a3d111067a953869e170ae5c834342\", \"ppapi_unittests\": \"25553a938262be4586ea04417c54a438fa1f7d97\", \"printing_unittests\": \"e8c06a34af3e9cfc95a57a496e27ee5d09e7fd0f\", \"remoting_unittests\": \"cfd8f8895b94e4bd2bec5e744e15d7dbe1c7a9ce\", \"sandbox_linux_unittests\": \"b7ba7f6ddc82d2d50ca2efc72ff608919c1fdeb4\", \"service_manager_unittests\": \"3c440aa6746f5e7f5dd2c3c80233145382217d7f\", \"services_unittests\": \"8643f53a710c2987367c7e5badbfc6d8711935f4\", \"shell_dialogs_unittests\": \"b166cbeecf1a880f1a0dbc8685ac3315eff429ca\", \"skia_unittests\": \"d2712f4554a099407e8ab14f8bbefcfbf4de759e\", \"snapshot_unittests\": \"7c8d1b816fe6941dfacb5a36d0308f85d3c076e6\", \"sql_unittests\": \"875e9263a713c1765425c33528d1bbbe2f0b0be8\", \"storage_unittests\": \"1ea90facfca25defd5b2d16761b4c613934a3918\", \"sync_integration_tests\": \"1749827ae64644035fee5ccbcdf70178296231ac\", \"telemetry_gpu_unittests\": \"705b7bc8eba26dbccb9fc036acef981d2343e062\", \"telemetry_perf_unittests\": \"84879a71d880257a4a87535b92552e4f54861a51\", \"telemetry_unittests\": \"215c3082d1ecebbc8c7ba6e46ab1ffeae7511d81\", \"traffic_annotation_auditor_unittests\": \"1b1376d0fef625282688f060a38863bc704d71fc\", \"ui_base_unittests\": \"6c32b605221663f246782354880a79a8b27a3d4f\", \"ui_touch_selection_unittests\": \"1c6138ecebb5cecf5adf271083ae75da4f8d9f6a\", \"unit_tests\": \"645ce16de4919bb9a4afe63f9bad294f4384d454\", \"url_unittests\": \"9ee045db5ccf047af98702c9f06fe271f6fb5bf4\", \"views_perftests\": \"64a37c136b9320b7537c47fa9363f9322eaddfc4\", \"views_unittests\": \"b93f3ddf8bb4c7b26c93264d7b7eaeeab2e17a83\", \"viz_unittests\": \"864c1de02b0aed9e85a7d5cf8ec222ff7bd23197\", \"vr_common_unittests\": \"6848b2390551effb28d69cbe35ee50999dc0723c\", \"vr_pixeltests\": \"12797fab4d2f494d9fc1cc76ab98b05ffaa9cbb2\", \"webdriver_wpt_tests\": \"f766a9f0619802446cf804520268425fa41b09e9\", \"weblayer_browsertests\": \"ebc974434a44ebbd191fcfbb7dad74fb7114c83d\", \"wm_unittests\": \"2b7b43c4434103f1573d612f78037fc15aa15768\", \"wtf_unittests\": \"297f3b0e16dc52be7a390a87e0a80e45cf827f02\", \"xr_browser_tests\": \"6ac672e2116845360fe1e60a1028ee6e257fb6bf\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_361cbaa79261c75a92fc63a8f8c6fc117764238b3277286fa51086e6da3301df_v2\", \"builder_479a9ab4e006ab9f281a30e38952e2c387e86fd61a54f6cd68389780c770d389_v2\", \"builder_5c21095a1a129131be723eb555273fa4f1dc89f7e6d0ed0ea2c09e97b0cf5fcb_v2\", \"builder_97b15bccca8d35086c1bc50eecf0b53d98289cdc65be75a287efcc384d1f34ed_v2\", \"builder_9ccb21220795a1d1972030c6b5d9b4569472a28790e1458f6676edb8e4495e8f_v2\", \"builder_b224075beee246a5ffe9706a23163fae567137407ac5f1b69c50b4dcd22edd9b_v2\", \"builder_c9091f6bc58db7983401393b6dacc93b3db8fae2c2ae9b40177b9e0904c8bc80_v2\", \"builder_d0ba3cd99c36b697d8651140d51bf4b4f4b9cd9aa25af11363af5ede5f6e35b1_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-31-yn20\"], \"image\": [\"chrome-xenial-19121500-b03549ec744\"], \"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\": [\"4745-ef32e44\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1578524524561777",
"status": "COMPLETED",
"status_changed_ts": "1578527618996288",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/167146",
"builder:Linux Builder (dbg)",
"buildset:commit/git/a7653678f328ed14ec77995afa94c9df857bd3fa",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a7653678f328ed14ec77995afa94c9df857bd3fa",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9057221162953384048",
"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/8891791099739506672/+/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:49a031cbbc554710",
"user_agent:luci-scheduler"
],
"updated_ts": "1578527619001939",
"url": "https://ci.chromium.org/b/8891791099739506672",
"utcnow_ts": "1578527619488065"
}
}
[23:53:39.500] Invocation finished in 56m38.412573519s with status SUCCEEDED