[22:01:36.787] New invocation is queued and will start shortly
[22:01:38.077] Starting the invocation (attempt 1)
[22:01:38.174] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:01:38.174] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:01:38.174] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9059580169883195872",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"8d044ed296511a7653ca5dd061540a65f52bee77\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9059580169883195872",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/8d044ed296511a7653ca5dd061540a65f52bee77",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8d044ed296511a7653ca5dd061540a65f52bee77",
"gitiles_ref:refs/heads/master"
]
}
[22:01:40.329] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1576274498930600",
"id": "8894150106968137568",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8d044ed296511a7653ca5dd061540a65f52bee77\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1576274500078308",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166059",
"builder:Linux Builder (dbg)",
"buildset:commit/git/8d044ed296511a7653ca5dd061540a65f52bee77",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8d044ed296511a7653ca5dd061540a65f52bee77",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059580169883195872",
"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/8894150106968137568/+/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": "1576274500078453",
"url": "https://ci.chromium.org/b/8894150106968137568",
"utcnow_ts": "1576274500315776"
}
}
[22:01:40.329] Task URL: https://ci.chromium.org/b/8894150106968137568
[22:01:40.329] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:2:0) after 1m0s
[22:02:40.462] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:2:0)
[22:02:40.462] Timer tick, asking Buildbucket for the build status
[22:02:43.852] Build 8894150106968137568: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:02:43.852] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:3:0) after 1m0s
[22:03:43.975] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:3:0)
[22:03:43.975] Timer tick, asking Buildbucket for the build status
[22:03:44.743] Build 8894150106968137568: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:03:44.743] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:4:0) after 1m0s
[22:04:44.821] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:4:0)
[22:04:44.821] Timer tick, asking Buildbucket for the build status
[22:04:45.508] Build 8894150106968137568: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:04:45.508] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:5:0) after 1m0s
[22:05:45.584] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:5:0)
[22:05:45.584] Timer tick, asking Buildbucket for the build status
[22:05:46.310] Build 8894150106968137568: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:05:46.310] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:6:0) after 1m0s
[22:06:04.396] Received PubSub notification, asking Buildbucket for the build status
[22:06:04.552] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:46.380] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:6:0)
[22:06:46.380] Timer tick, asking Buildbucket for the build status
[22:06:47.163] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:47.163] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:8:0) after 1m0s
[22:07:47.337] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:8:0)
[22:07:47.337] Timer tick, asking Buildbucket for the build status
[22:07:47.565] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:47.565] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:9:0) after 1m0s
[22:08:47.624] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:9:0)
[22:08:47.624] Timer tick, asking Buildbucket for the build status
[22:08:48.148] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:48.148] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:10:0) after 1m0s
[22:09:48.199] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:10:0)
[22:09:48.199] Timer tick, asking Buildbucket for the build status
[22:09:48.479] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:48.479] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:11:0) after 1m0s
[22:10:48.516] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:11:0)
[22:10:48.516] Timer tick, asking Buildbucket for the build status
[22:10:48.970] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:48.970] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:12:0) after 1m0s
[22:11:49.660] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:12:0)
[22:11:49.660] Timer tick, asking Buildbucket for the build status
[22:11:49.919] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:49.919] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:13:0) after 1m0s
[22:12:49.960] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:13:0)
[22:12:49.960] Timer tick, asking Buildbucket for the build status
[22:12:50.801] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:50.801] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:14:0) after 1m0s
[22:13:50.916] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:14:0)
[22:13:50.916] Timer tick, asking Buildbucket for the build status
[22:13:51.066] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:51.066] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:15:0) after 1m0s
[22:14:51.305] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:15:0)
[22:14:51.305] Timer tick, asking Buildbucket for the build status
[22:14:51.449] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:51.449] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:16:0) after 1m0s
[22:15:51.599] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:16:0)
[22:15:51.599] Timer tick, asking Buildbucket for the build status
[22:15:54.002] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:54.002] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:17:0) after 1m0s
[22:16:54.025] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:17:0)
[22:16:54.025] Timer tick, asking Buildbucket for the build status
[22:16:58.034] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:58.034] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:18:0) after 1m0s
[22:17:58.085] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:18:0)
[22:17:58.085] Timer tick, asking Buildbucket for the build status
[22:17:58.402] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:58.402] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:19:0) after 1m0s
[22:18:58.466] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:19:0)
[22:18:58.466] Timer tick, asking Buildbucket for the build status
[22:18:59.233] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:59.233] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:20:0) after 1m0s
[22:19:59.236] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:20:0)
[22:19:59.236] Timer tick, asking Buildbucket for the build status
[22:19:59.883] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:59.883] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:21:0) after 1m0s
[22:20:59.907] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:21:0)
[22:20:59.907] Timer tick, asking Buildbucket for the build status
[22:21:00.244] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:00.244] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:22:0) after 1m0s
[22:22:00.244] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:22:0)
[22:22:00.244] Timer tick, asking Buildbucket for the build status
[22:22:00.538] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:00.538] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:23:0) after 1m0s
[22:23:00.559] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:23:0)
[22:23:00.559] Timer tick, asking Buildbucket for the build status
[22:23:00.726] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:00.726] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:24:0) after 1m0s
[22:24:00.771] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:24:0)
[22:24:00.771] Timer tick, asking Buildbucket for the build status
[22:24:01.450] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:01.450] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:25:0) after 1m0s
[22:25:01.470] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:25:0)
[22:25:01.470] Timer tick, asking Buildbucket for the build status
[22:25:01.700] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:01.700] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:26:0) after 1m0s
[22:26:01.757] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:26:0)
[22:26:01.757] Timer tick, asking Buildbucket for the build status
[22:26:02.143] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:02.143] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:27:0) after 1m0s
[22:27:02.166] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:27:0)
[22:27:02.166] Timer tick, asking Buildbucket for the build status
[22:27:03.166] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:03.166] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:28:0) after 1m0s
[22:28:03.210] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:28:0)
[22:28:03.210] Timer tick, asking Buildbucket for the build status
[22:28:03.622] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:03.622] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:29:0) after 1m0s
[22:29:03.669] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:29:0)
[22:29:03.669] Timer tick, asking Buildbucket for the build status
[22:29:04.261] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:04.261] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:30:0) after 1m0s
[22:30:04.288] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:30:0)
[22:30:04.288] Timer tick, asking Buildbucket for the build status
[22:30:05.176] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:05.176] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:31:0) after 1m0s
[22:31:05.223] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:31:0)
[22:31:05.223] Timer tick, asking Buildbucket for the build status
[22:31:05.692] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:05.692] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:32:0) after 1m0s
[22:32:06.245] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:32:0)
[22:32:06.245] Timer tick, asking Buildbucket for the build status
[22:32:06.451] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:06.451] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:33:0) after 1m0s
[22:33:06.896] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:33:0)
[22:33:06.896] Timer tick, asking Buildbucket for the build status
[22:33:07.268] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:07.268] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:34:0) after 1m0s
[22:34:07.820] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:34:0)
[22:34:07.820] Timer tick, asking Buildbucket for the build status
[22:34:07.999] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:07.999] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:35:0) after 1m0s
[22:35:08.184] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:35:0)
[22:35:08.184] Timer tick, asking Buildbucket for the build status
[22:35:08.318] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:08.318] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:36:0) after 1m0s
[22:36:09.085] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:36:0)
[22:36:09.085] Timer tick, asking Buildbucket for the build status
[22:36:09.569] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:09.569] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:37:0) after 1m0s
[22:37:09.918] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:37:0)
[22:37:09.918] Timer tick, asking Buildbucket for the build status
[22:37:10.527] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:10.527] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:38:0) after 1m0s
[22:38:10.600] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:38:0)
[22:38:10.600] Timer tick, asking Buildbucket for the build status
[22:38:10.954] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:10.954] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:39:0) after 1m0s
[22:39:11.567] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:39:0)
[22:39:11.567] Timer tick, asking Buildbucket for the build status
[22:39:11.859] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:11.860] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:40:0) after 1m0s
[22:40:12.026] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:40:0)
[22:40:12.026] Timer tick, asking Buildbucket for the build status
[22:40:12.479] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:12.479] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:41:0) after 1m0s
[22:41:12.987] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:41:0)
[22:41:12.987] Timer tick, asking Buildbucket for the build status
[22:41:13.231] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:13.231] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:42:0) after 1m0s
[22:42:13.382] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:42:0)
[22:42:13.382] Timer tick, asking Buildbucket for the build status
[22:42:16.396] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:16.396] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:43:0) after 1m0s
[22:43:16.593] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:43:0)
[22:43:16.593] Timer tick, asking Buildbucket for the build status
[22:43:17.094] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:17.094] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:44:0) after 1m0s
[22:44:17.402] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:44:0)
[22:44:17.402] Timer tick, asking Buildbucket for the build status
[22:44:17.626] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:17.626] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:45:0) after 1m0s
[22:45:17.933] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:45:0)
[22:45:17.933] Timer tick, asking Buildbucket for the build status
[22:45:18.329] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:18.329] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:46:0) after 1m0s
[22:46:18.525] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:46:0)
[22:46:18.525] Timer tick, asking Buildbucket for the build status
[22:46:18.933] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:18.933] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:47:0) after 1m0s
[22:47:19.353] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:47:0)
[22:47:19.353] Timer tick, asking Buildbucket for the build status
[22:47:19.645] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:19.645] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:48:0) after 1m0s
[22:48:19.906] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:48:0)
[22:48:19.906] Timer tick, asking Buildbucket for the build status
[22:48:20.093] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:20.093] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:49:0) after 1m0s
[22:49:20.161] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:49:0)
[22:49:20.161] Timer tick, asking Buildbucket for the build status
[22:49:20.336] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:20.336] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:50:0) after 1m0s
[22:50:20.402] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:50:0)
[22:50:20.402] Timer tick, asking Buildbucket for the build status
[22:50:20.799] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:20.799] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:51:0) after 1m0s
[22:51:20.933] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:51:0)
[22:51:20.933] Timer tick, asking Buildbucket for the build status
[22:51:21.349] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:21.349] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:52:0) after 1m0s
[22:52:21.553] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:52:0)
[22:52:21.553] Timer tick, asking Buildbucket for the build status
[22:52:22.035] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:22.035] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:53:0) after 1m0s
[22:53:22.097] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:53:0)
[22:53:22.097] Timer tick, asking Buildbucket for the build status
[22:53:22.626] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:22.626] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:54:0) after 1m0s
[22:54:22.687] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:54:0)
[22:54:22.687] Timer tick, asking Buildbucket for the build status
[22:54:22.854] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:22.854] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:55:0) after 1m0s
[22:55:22.916] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:55:0)
[22:55:22.916] Timer tick, asking Buildbucket for the build status
[22:55:23.703] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:23.703] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:56:0) after 1m0s
[22:56:23.810] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:56:0)
[22:56:23.810] Timer tick, asking Buildbucket for the build status
[22:56:24.120] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:24.120] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:57:0) after 1m0s
[22:57:24.405] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:57:0)
[22:57:24.405] Timer tick, asking Buildbucket for the build status
[22:57:25.144] Build 8894150106968137568: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:25.144] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059580169883195872:58:0) after 1m0s
[22:57:58.578] Received PubSub notification, asking Buildbucket for the build status
[22:57:59.073] Build 8894150106968137568: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:57:59.073] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1576277877661762",
"created_by": "project:chromium",
"created_ts": "1576274498930600",
"id": "8894150106968137568",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8d044ed296511a7653ca5dd061540a65f52bee77\"}}",
"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-central1-b-8-80-b2jq\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 166059, \"got_angle_revision\": \"e181f2f7cf172afd95b9646283bd910caaa1b721\", \"got_dawn_revision\": \"77fd4750ae5ad3ee9f281089fddfeaca56ed049c\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"8d044ed296511a7653ca5dd061540a65f52bee77\", \"got_revision_cp\": \"refs/heads/master@{#724795}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"468ee1576da76a2d6b6e4861d9a874944ab7b959\", \"got_v8_revision\": \"259dff3db0cc5126c95efc07addd5b380b93da48\", \"got_v8_revision_cp\": \"refs/heads/8.1.30@{#1}\", \"got_webrtc_revision\": \"41466b7bef5aa0927e3724f53c556bc4019f80d4\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30087}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8d044ed296511a7653ca5dd061540a65f52bee77\", \"swarm_hashes_refs/heads/master(at){#724795}_without_patch\": {\"accessibility_unittests\": \"4bce745071f84b68e3858753f656e43e30bacd39\", \"angle_unittests\": \"ac38e6248978d9ebe16f276f09498e3af1a9d4ec\", \"app_shell_unittests\": \"c49a84141ce26708e418a71659809d5927321304\", \"aura_unittests\": \"1f92a7e56221a91d787f46b4eeba9f61fc1814ef\", \"base_unittests\": \"6516c13b0109334f859836623fc1078534a7bc0c\", \"base_util_unittests\": \"5c5a4a37861a68c5368d60f5c39fb839480c4640\", \"blink_common_unittests\": \"a88f4e2fbac4d1f6ffad64b846e9fd1664ea9a5a\", \"blink_fuzzer_unittests\": \"d448940c996a2689e7218ecd4258f43ce7162e89\", \"blink_heap_unittests\": \"cfebb56c176e9a585fbc939757d1365830a53439\", \"blink_platform_unittests\": \"2a8975e10cb283987374863928222587fa1232d2\", \"blink_python_tests\": \"d82713f5b3a98a41ecd62cc3a2e1013ccf1bca85\", \"blink_unittests\": \"10a662e50e5efe624b4d1b4fe8e53ac9164fbc28\", \"blink_web_tests\": \"8f99b2c957ec6a4696dabde20f33e55b4d040b60\", \"boringssl_crypto_tests\": \"e67155d51c588bbd3aa75a0d7f3529a6f1eb6871\", \"boringssl_ssl_tests\": \"8a0b0f3bbcfdb04c0f6e77105dc5b4bb115f3135\", \"browser_tests\": \"fe13fa173549cb3bdb6ee52799aa509e83436e2e\", \"cacheinvalidation_unittests\": \"828a105ad04d719f56e47e1d2a60a476281ccb0b\", \"capture_unittests\": \"82b98caec0bd6f18540a6459ce4a1ddf6afa2139\", \"cast_unittests\": \"fb2d2aa2ed010d22268cb222c94abfcbb2842542\", \"cc_unittests\": \"4c8100121fc982578bd7dea555f746494561c771\", \"chrome_app_unittests\": \"b39b2cdfcf222db8d14ca61ee0b1d7ef8fa0a4fa\", \"chromedriver_unittests\": \"77e4cab78a4ed3298d62e4e8d2ea231ccdd82785\", \"components_browsertests\": \"8e76386c8170e87a2c9188c8dbba6a9efc405cc5\", \"components_unittests\": \"f12875224ef1f8e1eae6646bb5c78e138b744880\", \"compositor_unittests\": \"662b8c320de2be60d1e987b731fb7fcabdb12ae4\", \"content_browsertests\": \"8239e7382488eff27635454b7fc1ea9c67c46d33\", \"content_shell_crash_test\": \"9f998f90a6fdada7988a225b9172aae7de27f7ce\", \"content_unittests\": \"dba52af3f88beaa3805a4fcc2c9323e02f9ac13e\", \"crashpad_tests\": \"23d7e1d1a6c0718f1920ef87c09d5f7e017727c3\", \"cronet_tests\": \"1af8c64764aec062324e8a71d2f9f1c13dc996d3\", \"cronet_unittests\": \"3c1ffcea612930d480b710314476ca5cc7e6299d\", \"crypto_unittests\": \"0633404b02b62f61cfaa06967f4b62c11c1b3a7a\", \"dbus_unittests\": \"d1fd1a7855a6ef9aa3222ccf9786124dcdeb4a36\", \"device_unittests\": \"397a66806bb1c268efe9d7595be838212b360bcf\", \"devtools_lint_check\": \"1bf2f108f8410c21e2a04749bd05c0eb49e79b0e\", \"devtools_type_check\": \"d1c26d88e2a8ccfac08e83823baf80cce4317e5c\", \"display_unittests\": \"03146e615df630d79f780f07a19f68b50a8a7df7\", \"events_unittests\": \"b331ee14d71393ad215d57c20bd25f3205870880\", \"extensions_browsertests\": \"0a5c34464a28aff109d5dd76153bb8fa8abac2d4\", \"extensions_unittests\": \"cc3f628bbbdf8067ee8c62e59b48467c552495ac\", \"filesystem_service_unittests\": \"55d740db9ffda515258e656a262a03c7f48e5869\", \"flatbuffers_unittests\": \"034d1c63a8199a680c9bbf29b5866b1c851e28ee\", \"gcm_unit_tests\": \"e1920c5428bf62fdf911cc7d9a1f3c195aa1d1c6\", \"gfx_unittests\": \"9b56cbb7c3d75cd32992a20e4f2e5cfa9461db2b\", \"gin_unittests\": \"96918ceeb3ad3b523f6c2a34db6b12d89addb431\", \"google_apis_unittests\": \"5846627fdeedc6b196ea6117d9aeeebab716d417\", \"gpu_unittests\": \"ca65400ddbd3c58c555200f96ab7110d9c80df6c\", \"grit_python_unittests\": \"25dff9ef6327c596609d376409bc1d0e605b6d43\", \"gwp_asan_unittests\": \"0ad3a6779628575ac9aa2d706758510878814fa3\", \"headless_browsertests\": \"2663806d1794860fbe025ec8279dc068d5ef39cd\", \"headless_unittests\": \"eb3c5eccaae14e58c5c24249edd85f1ecf8239af\", \"interactive_ui_tests\": \"815a7aec317f3034910bc8804458692f5188e877\", \"ipc_tests\": \"1494995cba926ae22e98c764916ad431e3972237\", \"jingle_unittests\": \"86276aa4902ccfb515c3ad7ea8487172b31f9394\", \"latency_unittests\": \"3ff56eada0db176c17e5ce914268084d50fe540c\", \"libjingle_xmpp_unittests\": \"e04b42c11b900eaf4da78f4035d5af02a0bf15ec\", \"media_blink_unittests\": \"28371b07a5328658b84fa000aec1c024209e789c\", \"media_service_unittests\": \"f72b90a849feb9a79f9a214c3689a0216be97c7b\", \"media_unittests\": \"026a7f4f32d6b8428226a929516ef856430c05e8\", \"message_center_unittests\": \"c00ab9e81cabeca761162d1337a3b750f860c6ee\", \"metrics_python_tests\": \"4655f143d3f778e8cc3e18af9c939dccc48e3ccd\", \"midi_unittests\": \"9c562f8734d0576cd3c1dcf2f72134634329ab1e\", \"mojo_core_unittests\": \"e31c83ec714b35637aaff43d6c1e9f816a536486\", \"mojo_unittests\": \"cbdc6cc628b32d7225a62a9d2c2c7124a4c525ca\", \"nacl_helper_nonsfi_unittests\": \"a6a55acb2d8bb44db90ae483beb729483da87550\", \"nacl_loader_unittests\": \"7816b0457488cacbfbb57f1537ba409ece6f1065\", \"native_theme_unittests\": \"440b542bf89711b16e67ee4ae1a0bd6caa553e15\", \"net_unittests\": \"012b5e9a7f3154321b7ff649cef638264a19c999\", \"pdf_unittests\": \"df9ba5a9df74a7de53132873aced05bdb04a31e2\", \"perfetto_unittests\": \"85a1d632bce9352aab386e88c73ce8098d3897e9\", \"ppapi_unittests\": \"21d80fd3a9d1654485c71e0108c172d9306a4e94\", \"printing_unittests\": \"3a4de8692cc5ef3f57d293cf45228b41e466bb42\", \"remoting_unittests\": \"02fc5378dc513d68064e188d1a3323156be89972\", \"sandbox_linux_unittests\": \"e256da7aa667c2534642fa52b91f00cfc7eb0400\", \"service_manager_unittests\": \"a764b632717599ac0f16e69f642565de2d928564\", \"services_unittests\": \"5d0934a3a66525a4b76e8364377be3074af3c3d1\", \"shell_dialogs_unittests\": \"b00cef3a3966f864dd03e3023eed2a6b754ce13f\", \"skia_unittests\": \"a5948baa34494237def01a3638a5b55590db3a5a\", \"snapshot_unittests\": \"1d8941e2b27a012d91b5558bfd60bf9588bdbe79\", \"sql_unittests\": \"e8b55a5acda6b0786d9160ed00ad8c8df669b92f\", \"storage_unittests\": \"f840e74dc1fe6c1608a17c900992323e1b71876f\", \"sync_integration_tests\": \"d4c986bc17e368c3e635708c87c4d07d807bf7ec\", \"telemetry_gpu_unittests\": \"4005810fc60db156d26912b4f294e99baf5d871e\", \"telemetry_perf_unittests\": \"2b238f9e7d76790bdc4a89438d5da9d17927110c\", \"telemetry_unittests\": \"5f5ffd2714bf603f059b9170010ebe8e96377a7a\", \"traffic_annotation_auditor_unittests\": \"aebc834f6e6965a8e78429b042bcc658d10d6b61\", \"ui_base_unittests\": \"d1a4f4a296e862c4dde3ff92d6b9af32326c3625\", \"ui_touch_selection_unittests\": \"afb4e7002e680cce49d0cf61dd58b428ab1b592d\", \"unit_tests\": \"80ebc0359466fdda32c4a926fc107c26a6b7ed17\", \"url_unittests\": \"8ad11f448a82e8fd6fabbcfea550d58f241d1a92\", \"views_perftests\": \"ef8cafa266e91b58146f60aa1e352160491d0c8f\", \"views_unittests\": \"22b0fe21d6edcb95e28eda000b02a235ec0991f5\", \"viz_unittests\": \"7d7994c033a8aa3bc6bfc2989c7aed7c7d4b4db0\", \"vr_common_unittests\": \"e12350f4a48707ed7540bca173a23eb3bbac4eb3\", \"vr_pixeltests\": \"51881704c2df42c70fcc343b133379b31a8de668\", \"webdriver_wpt_tests\": \"b2c2675cb8cf7d9b68cc27144a0d78309ae754ee\", \"weblayer_browsertests\": \"9c00bf25cbc6fdc4f5386f613a19d37cb8ab15c9\", \"wm_unittests\": \"cc4ce656adeb275f8873b1195746d5405fed3022\", \"wtf_unittests\": \"0c7364d5563a86fa3324f2bbf654152bd2996b8f\", \"xr_browser_tests\": \"028b026db2df815aa503993af22613a0e4775e05\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_73a5b2ce307b316a1240917eaad41a92e4adc44903c6789e05a5f10300306cda_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-central1-b-8-80-b2jq\"], \"image\": [\"chrome-xenial-19120800-09d8782d9a1\"], \"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\": [\"4724-8894a57\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1576274763447237",
"status": "COMPLETED",
"status_changed_ts": "1576277877984458",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166059",
"builder:Linux Builder (dbg)",
"buildset:commit/git/8d044ed296511a7653ca5dd061540a65f52bee77",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8d044ed296511a7653ca5dd061540a65f52bee77",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059580169883195872",
"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/8894150106968137568/+/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:491a19e0d62f1c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1576277878045661",
"url": "https://ci.chromium.org/b/8894150106968137568",
"utcnow_ts": "1576277879046247"
}
}
[22:57:59.073] Invocation finished in 56m22.30577523s with status SUCCEEDED