[21:56:13.136] New invocation is queued and will start shortly
[21:56:14.404] Starting the invocation (attempt 1)
[21:56:14.500] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:56:14.502] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:56:14.502] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9034485149560335520",
"parameters_json": "{\"builder_name\":\"Linux Viz\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d9daac114a9f5c8a7bc99042d92f40dcd62c3d08\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Viz",
"scheduler_invocation_id:9034485149560335520",
"scheduler_job_id:chromium/Linux Viz",
"user_agent:luci-scheduler",
"buildset:commit/git/d9daac114a9f5c8a7bc99042d92f40dcd62c3d08",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d9daac114a9f5c8a7bc99042d92f40dcd62c3d08",
"gitiles_ref:refs/heads/master"
]
}
[21:56:15.356] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1600206974680825",
"id": "8869055087276596896",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d9daac114a9f5c8a7bc99042d92f40dcd62c3d08\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1600206975215509",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/23909",
"builder:Linux Viz",
"buildset:commit/git/d9daac114a9f5c8a7bc99042d92f40dcd62c3d08",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d9daac114a9f5c8a7bc99042d92f40dcd62c3d08",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9034485149560335520",
"scheduler_job_id:chromium/Linux Viz",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8869055087276596896/+/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": "1600206975215710",
"url": "https://ci.chromium.org/b/8869055087276596896",
"utcnow_ts": "1600206975342034"
}
}
[21:56:15.356] Task URL: https://ci.chromium.org/b/8869055087276596896
[21:56:15.356] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:2:0) after 1m0s
[21:56:17.741] Received PubSub notification, asking Buildbucket for the build status
[21:56:17.852] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:15.419] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:2:0)
[21:57:15.419] Timer tick, asking Buildbucket for the build status
[21:57:15.551] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:15.551] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:4:0) after 1m0s
[21:58:15.642] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:4:0)
[21:58:15.642] Timer tick, asking Buildbucket for the build status
[21:58:15.750] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:15.750] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:5:0) after 1m0s
[21:59:15.819] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:5:0)
[21:59:15.819] Timer tick, asking Buildbucket for the build status
[21:59:16.230] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:16.230] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:6:0) after 1m0s
[22:00:16.285] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:6:0)
[22:00:16.285] Timer tick, asking Buildbucket for the build status
[22:00:16.437] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:16.437] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:7:0) after 1m0s
[22:01:16.514] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:7:0)
[22:01:16.515] Timer tick, asking Buildbucket for the build status
[22:01:16.615] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:16.615] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:8:0) after 1m0s
[22:02:16.656] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:8:0)
[22:02:16.656] Timer tick, asking Buildbucket for the build status
[22:02:16.751] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:16.751] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:9:0) after 1m0s
[22:03:16.754] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:9:0)
[22:03:16.754] Timer tick, asking Buildbucket for the build status
[22:03:16.823] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:16.823] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:10:0) after 1m0s
[22:04:16.879] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:10:0)
[22:04:16.879] Timer tick, asking Buildbucket for the build status
[22:04:16.966] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:16.966] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:11:0) after 1m0s
[22:05:17.005] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:11:0)
[22:05:17.005] Timer tick, asking Buildbucket for the build status
[22:05:17.381] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:17.381] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:12:0) after 1m0s
[22:06:17.484] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:12:0)
[22:06:17.484] Timer tick, asking Buildbucket for the build status
[22:06:18.014] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:18.014] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:13:0) after 1m0s
[22:07:18.020] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:13:0)
[22:07:18.020] Timer tick, asking Buildbucket for the build status
[22:07:18.276] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:18.276] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:14:0) after 1m0s
[22:08:18.357] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:14:0)
[22:08:18.357] Timer tick, asking Buildbucket for the build status
[22:08:18.445] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:18.445] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:15:0) after 1m0s
[22:09:18.552] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:15:0)
[22:09:18.552] Timer tick, asking Buildbucket for the build status
[22:09:18.659] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:18.659] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:16:0) after 1m0s
[22:10:18.749] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:16:0)
[22:10:18.749] Timer tick, asking Buildbucket for the build status
[22:10:18.833] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:18.833] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:17:0) after 1m0s
[22:11:18.828] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:17:0)
[22:11:18.828] Timer tick, asking Buildbucket for the build status
[22:11:19.212] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:19.212] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:18:0) after 1m0s
[22:12:19.263] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:18:0)
[22:12:19.263] Timer tick, asking Buildbucket for the build status
[22:12:19.337] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:19.337] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:19:0) after 1m0s
[22:13:19.373] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:19:0)
[22:13:19.373] Timer tick, asking Buildbucket for the build status
[22:13:19.576] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:19.576] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:20:0) after 1m0s
[22:14:19.602] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:20:0)
[22:14:19.602] Timer tick, asking Buildbucket for the build status
[22:14:19.817] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:19.817] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:21:0) after 1m0s
[22:15:19.813] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:21:0)
[22:15:19.813] Timer tick, asking Buildbucket for the build status
[22:15:20.129] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:20.129] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:22:0) after 1m0s
[22:16:20.169] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:22:0)
[22:16:20.169] Timer tick, asking Buildbucket for the build status
[22:16:20.318] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:20.318] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:23:0) after 1m0s
[22:17:20.403] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:23:0)
[22:17:20.403] Timer tick, asking Buildbucket for the build status
[22:17:20.481] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:20.481] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:24:0) after 1m0s
[22:18:20.515] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:24:0)
[22:18:20.515] Timer tick, asking Buildbucket for the build status
[22:18:20.746] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:20.746] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:25:0) after 1m0s
[22:19:20.801] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:25:0)
[22:19:20.801] Timer tick, asking Buildbucket for the build status
[22:19:21.023] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:21.023] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:26:0) after 1m0s
[22:20:21.119] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:26:0)
[22:20:21.119] Timer tick, asking Buildbucket for the build status
[22:20:21.235] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:21.235] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:27:0) after 1m0s
[22:21:21.285] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:27:0)
[22:21:21.285] Timer tick, asking Buildbucket for the build status
[22:21:21.413] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:21.413] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:28:0) after 1m0s
[22:22:21.468] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:28:0)
[22:22:21.468] Timer tick, asking Buildbucket for the build status
[22:22:21.827] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:21.827] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:29:0) after 1m0s
[22:23:22.014] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:29:0)
[22:23:22.014] Timer tick, asking Buildbucket for the build status
[22:23:22.139] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:22.139] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:30:0) after 1m0s
[22:24:22.242] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:30:0)
[22:24:22.242] Timer tick, asking Buildbucket for the build status
[22:24:22.401] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:22.401] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:31:0) after 1m0s
[22:25:22.400] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:31:0)
[22:25:22.400] Timer tick, asking Buildbucket for the build status
[22:25:22.466] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:22.466] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:32:0) after 1m0s
[22:26:22.553] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:32:0)
[22:26:22.553] Timer tick, asking Buildbucket for the build status
[22:26:22.685] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:22.685] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:33:0) after 1m0s
[22:27:22.775] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:33:0)
[22:27:22.775] Timer tick, asking Buildbucket for the build status
[22:27:22.859] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:22.859] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:34:0) after 1m0s
[22:28:22.890] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:34:0)
[22:28:22.890] Timer tick, asking Buildbucket for the build status
[22:28:23.398] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:23.398] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:35:0) after 1m0s
[22:29:23.477] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:35:0)
[22:29:23.477] Timer tick, asking Buildbucket for the build status
[22:29:23.556] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:23.556] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:36:0) after 1m0s
[22:30:23.577] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:36:0)
[22:30:23.577] Timer tick, asking Buildbucket for the build status
[22:30:23.642] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:23.642] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:37:0) after 1m0s
[22:31:23.668] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:37:0)
[22:31:23.668] Timer tick, asking Buildbucket for the build status
[22:31:23.794] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:23.794] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:38:0) after 1m0s
[22:32:23.809] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:38:0)
[22:32:23.809] Timer tick, asking Buildbucket for the build status
[22:32:23.881] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:23.881] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:39:0) after 1m0s
[22:33:23.949] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:39:0)
[22:33:23.949] Timer tick, asking Buildbucket for the build status
[22:33:24.465] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:24.465] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:40:0) after 1m0s
[22:34:24.502] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:40:0)
[22:34:24.502] Timer tick, asking Buildbucket for the build status
[22:34:24.568] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:24.568] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:41:0) after 1m0s
[22:35:24.593] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:41:0)
[22:35:24.593] Timer tick, asking Buildbucket for the build status
[22:35:24.719] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:24.719] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:42:0) after 1m0s
[22:36:24.738] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:42:0)
[22:36:24.738] Timer tick, asking Buildbucket for the build status
[22:36:25.230] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:25.230] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:43:0) after 1m0s
[22:37:25.295] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:43:0)
[22:37:25.295] Timer tick, asking Buildbucket for the build status
[22:37:25.418] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:25.418] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:44:0) after 1m0s
[22:38:25.502] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:44:0)
[22:38:25.502] Timer tick, asking Buildbucket for the build status
[22:38:25.604] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:25.604] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:45:0) after 1m0s
[22:39:25.679] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:45:0)
[22:39:25.679] Timer tick, asking Buildbucket for the build status
[22:39:25.750] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:25.750] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:46:0) after 1m0s
[22:40:25.822] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:46:0)
[22:40:25.822] Timer tick, asking Buildbucket for the build status
[22:40:25.886] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:25.886] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:47:0) after 1m0s
[22:41:25.891] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:47:0)
[22:41:25.891] Timer tick, asking Buildbucket for the build status
[22:41:25.994] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:25.994] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:48:0) after 1m0s
[22:42:26.083] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:48:0)
[22:42:26.083] Timer tick, asking Buildbucket for the build status
[22:42:26.388] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:26.388] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:49:0) after 1m0s
[22:43:26.487] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:49:0)
[22:43:26.487] Timer tick, asking Buildbucket for the build status
[22:43:26.777] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:26.778] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:50:0) after 1m0s
[22:44:26.858] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:50:0)
[22:44:26.858] Timer tick, asking Buildbucket for the build status
[22:44:27.194] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:27.194] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:51:0) after 1m0s
[22:45:27.206] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:51:0)
[22:45:27.206] Timer tick, asking Buildbucket for the build status
[22:45:27.352] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:27.352] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:52:0) after 1m0s
[22:46:27.402] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:52:0)
[22:46:27.402] Timer tick, asking Buildbucket for the build status
[22:46:27.497] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:27.497] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:53:0) after 1m0s
[22:47:27.516] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:53:0)
[22:47:27.516] Timer tick, asking Buildbucket for the build status
[22:47:27.685] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:27.685] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:54:0) after 1m0s
[22:48:27.950] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:54:0)
[22:48:27.950] Timer tick, asking Buildbucket for the build status
[22:48:28.085] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:28.085] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:55:0) after 1m0s
[22:49:28.174] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:55:0)
[22:49:28.174] Timer tick, asking Buildbucket for the build status
[22:49:28.373] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:28.373] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:56:0) after 1m0s
[22:50:28.440] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:56:0)
[22:50:28.440] Timer tick, asking Buildbucket for the build status
[22:50:28.584] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:28.584] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:57:0) after 1m0s
[22:51:28.605] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:57:0)
[22:51:28.605] Timer tick, asking Buildbucket for the build status
[22:51:28.833] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:28.833] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:58:0) after 1m0s
[22:52:28.906] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:58:0)
[22:52:28.906] Timer tick, asking Buildbucket for the build status
[22:52:28.986] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:28.986] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:59:0) after 1m0s
[22:53:29.056] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:59:0)
[22:53:29.056] Timer tick, asking Buildbucket for the build status
[22:53:29.141] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:29.141] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:60:0) after 1m0s
[22:54:29.196] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:60:0)
[22:54:29.196] Timer tick, asking Buildbucket for the build status
[22:54:29.305] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:29.305] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:61:0) after 1m0s
[22:55:29.297] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:61:0)
[22:55:29.298] Timer tick, asking Buildbucket for the build status
[22:55:29.518] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:29.518] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:62:0) after 1m0s
[22:56:29.582] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:62:0)
[22:56:29.582] Timer tick, asking Buildbucket for the build status
[22:56:29.687] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:29.687] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:63:0) after 1m0s
[22:57:29.717] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:63:0)
[22:57:29.717] Timer tick, asking Buildbucket for the build status
[22:57:29.777] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:29.778] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:64:0) after 1m0s
[22:58:29.879] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:64:0)
[22:58:29.879] Timer tick, asking Buildbucket for the build status
[22:58:30.047] Build 8869055087276596896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:30.047] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9034485149560335520:65:0) after 1m0s
[22:58:46.916] Received PubSub notification, asking Buildbucket for the build status
[22:58:47.028] Build 8869055087276596896: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[22:58:47.028] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1600210726261016",
"created_by": "project:chromium",
"created_ts": "1600206974680825",
"failure_reason": "BUILD_FAILURE",
"id": "8869055087276596896",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d9daac114a9f5c8a7bc99042d92f40dcd62c3d08\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**skia_renderer_content_browsertests** failed because of:\\n\\n- RenderFrameHostImplBrowserTest.SameSiteNavigationResetsDocumentUsedWebOTP\"}, \"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"d9daac114a9f5c8a7bc99042d92f40dcd62c3d08\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-110-cpn5\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.fyi\", \"buildername\": \"Linux Viz\", \"buildnumber\": 23909, \"got_angle_revision\": \"377e748714bfcb827a2a61a3b8ccf8ea8fab2156\", \"got_dawn_revision\": \"dbd34449af20f2d026b1e7f959073e254066ec83\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"d9daac114a9f5c8a7bc99042d92f40dcd62c3d08\", \"got_revision_cp\": \"refs/heads/master@{#807193}\", \"got_swarming_client_revision\": \"44c13d73156581ea09b9389001e58c23a4b8d70a\", \"got_swiftshader_revision\": \"b33e0b36a08cc7a69e0b063ddc3602124d8227d9\", \"got_v8_revision\": \"ff50ca34d46076a54b805f4f916cdd3e5805ccba\", \"got_v8_revision_cp\": \"refs/heads/8.7.88@{#1}\", \"got_webrtc_revision\": \"986e7451061384222b6fc6ca3110e56cda08597a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32106}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d9daac114a9f5c8a7bc99042d92f40dcd62c3d08\", \"swarm_hashes\": {\"blink_web_tests\": \"7a9631a52d339c21b3d77200c451e8f4e2b20e09\", \"browser_tests\": \"451ef85af2bd84e7c23a3a0661edec497002985b\", \"content_browsertests\": \"8f834e6a64d4112d4eaf61e403bf5de2fa931f55\"}, \"swarm_hashes_refs/heads/master(at){#807193}_without_patch\": {\"blink_web_tests\": \"7a9631a52d339c21b3d77200c451e8f4e2b20e09\", \"browser_tests\": \"451ef85af2bd84e7c23a3a0661edec497002985b\", \"content_browsertests\": \"8f834e6a64d4112d4eaf61e403bf5de2fa931f55\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_5315cf154f9c1ae6b2621094599bc107171a849e1c0f232ec66a504ee800a364_v2\", \"builder_5e40e9df3c25d6ec04b042625e64587de25b7cc9257c9b922c4342550ccccbb8_v2\", \"builder_a3249012ab2e105b4f1a10f0dc352b422f692fd701d889292e5c6170d198db4b_v2\", \"builder_b5e41ce0b01ce81870a1097ddb1343f2a3924630f4d3bb9f1a0eaa4d6a5abbed_v2\", \"builder_bea46eaeedb7de1bd52f00a0308b818fe481ae542888a2fa5825ab1ef5ba3493_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-110-cpn5\"], \"image\": [\"chrome-xenial-20090600-f98a2a06cf8\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5522-67570db\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**skia_renderer_content_browsertests** failed because of:\\n\\n- RenderFrameHostImplBrowserTest.SameSiteNavigationResetsDocumentUsedWebOTP\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1600206977057092",
"status": "COMPLETED",
"status_changed_ts": "1600206977352193",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/23909",
"builder:Linux Viz",
"buildset:commit/git/d9daac114a9f5c8a7bc99042d92f40dcd62c3d08",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d9daac114a9f5c8a7bc99042d92f40dcd62c3d08",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9034485149560335520",
"scheduler_job_id:chromium/Linux Viz",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8869055087276596896/+/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:4eac963abe9e7f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1600210726464779",
"url": "https://ci.chromium.org/b/8869055087276596896",
"utcnow_ts": "1600210727014551"
}
}
[22:58:47.028] Invocation finished in 1h2m33.909085562s with status FAILED