[19:49:42.252] New invocation is queued and will start shortly
[19:49:43.496] Starting the invocation (attempt 1)
[19:49:43.556] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:49:43.556] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:49:43.556] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027245351893458592",
"parameters_json": "{\"builder_name\":\"Linux Viz\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"3883710078f4fa58b1ce1f2230d8e3b96241c243\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Viz",
"scheduler_invocation_id:9027245351893458592",
"scheduler_job_id:chromium/Linux Viz",
"user_agent:luci-scheduler",
"buildset:commit/git/3883710078f4fa58b1ce1f2230d8e3b96241c243",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3883710078f4fa58b1ce1f2230d8e3b96241c243",
"gitiles_ref:refs/heads/master"
]
}
[19:49:44.291] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607111383672996",
"id": "8861815289713076464",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3883710078f4fa58b1ce1f2230d8e3b96241c243\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607111384063054",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/25176",
"builder:Linux Viz",
"buildset:commit/git/3883710078f4fa58b1ce1f2230d8e3b96241c243",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3883710078f4fa58b1ce1f2230d8e3b96241c243",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027245351893458592",
"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/8861815289713076464/+/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": "1607111384063253",
"url": "https://ci.chromium.org/b/8861815289713076464",
"utcnow_ts": "1607111384281213"
}
}
[19:49:44.291] Task URL: https://ci.chromium.org/b/8861815289713076464
[19:49:44.291] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:2:0) after 1m0s
[19:50:44.334] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:2:0)
[19:50:44.334] Timer tick, asking Buildbucket for the build status
[19:50:44.531] Build 8861815289713076464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:50:44.531] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:3:0) after 1m0s
[19:51:44.554] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:3:0)
[19:51:44.554] Timer tick, asking Buildbucket for the build status
[19:51:44.703] Build 8861815289713076464: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[19:51:44.703] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:4:0) after 1m0s
[19:52:22.253] Received PubSub notification, asking Buildbucket for the build status
[19:52:24.798] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:44.801] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:4:0)
[19:52:44.802] Timer tick, asking Buildbucket for the build status
[19:52:44.963] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:44.963] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:6:0) after 1m0s
[19:53:44.997] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:6:0)
[19:53:44.997] Timer tick, asking Buildbucket for the build status
[19:53:45.138] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:45.138] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:7:0) after 1m0s
[19:54:45.141] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:7:0)
[19:54:45.141] Timer tick, asking Buildbucket for the build status
[19:54:45.484] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:45.484] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:8:0) after 1m0s
[19:55:45.478] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:8:0)
[19:55:45.478] Timer tick, asking Buildbucket for the build status
[19:55:45.801] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:45.801] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:9:0) after 1m0s
[19:56:45.851] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:9:0)
[19:56:45.851] Timer tick, asking Buildbucket for the build status
[19:56:46.329] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:46.329] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:10:0) after 1m0s
[19:57:46.383] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:10:0)
[19:57:46.383] Timer tick, asking Buildbucket for the build status
[19:57:46.464] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:46.464] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:11:0) after 1m0s
[19:58:46.501] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:11:0)
[19:58:46.501] Timer tick, asking Buildbucket for the build status
[19:58:46.851] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:46.851] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:12:0) after 1m0s
[19:59:46.914] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:12:0)
[19:59:46.914] Timer tick, asking Buildbucket for the build status
[19:59:47.071] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:47.071] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:13:0) after 1m0s
[20:00:47.139] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:13:0)
[20:00:47.139] Timer tick, asking Buildbucket for the build status
[20:00:47.613] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:47.613] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:14:0) after 1m0s
[20:01:47.809] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:14:0)
[20:01:47.809] Timer tick, asking Buildbucket for the build status
[20:01:48.225] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:48.225] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:15:0) after 1m0s
[20:02:48.297] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:15:0)
[20:02:48.297] Timer tick, asking Buildbucket for the build status
[20:02:49.165] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:49.165] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:16:0) after 1m0s
[20:03:49.190] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:16:0)
[20:03:49.190] Timer tick, asking Buildbucket for the build status
[20:03:49.475] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:49.475] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:17:0) after 1m0s
[20:04:49.522] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:17:0)
[20:04:49.522] Timer tick, asking Buildbucket for the build status
[20:04:49.663] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:49.663] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:18:0) after 1m0s
[20:05:49.647] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:18:0)
[20:05:49.647] Timer tick, asking Buildbucket for the build status
[20:05:49.712] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:49.712] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:19:0) after 1m0s
[20:06:49.746] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:19:0)
[20:06:49.746] Timer tick, asking Buildbucket for the build status
[20:06:50.694] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:50.694] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:20:0) after 1m0s
[20:07:50.702] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:20:0)
[20:07:50.702] Timer tick, asking Buildbucket for the build status
[20:07:50.794] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:50.794] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:21:0) after 1m0s
[20:08:50.842] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:21:0)
[20:08:50.842] Timer tick, asking Buildbucket for the build status
[20:08:50.919] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:50.919] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:22:0) after 1m0s
[20:09:50.976] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:22:0)
[20:09:50.976] Timer tick, asking Buildbucket for the build status
[20:09:51.127] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:51.127] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:23:0) after 1m0s
[20:10:51.155] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:23:0)
[20:10:51.155] Timer tick, asking Buildbucket for the build status
[20:10:51.336] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:51.336] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:24:0) after 1m0s
[20:11:51.383] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:24:0)
[20:11:51.383] Timer tick, asking Buildbucket for the build status
[20:11:51.526] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:51.526] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:25:0) after 1m0s
[20:12:51.570] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:25:0)
[20:12:51.570] Timer tick, asking Buildbucket for the build status
[20:12:51.641] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:51.641] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:26:0) after 1m0s
[20:13:51.633] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:26:0)
[20:13:51.633] Timer tick, asking Buildbucket for the build status
[20:13:51.700] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:51.700] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:27:0) after 1m0s
[20:14:51.713] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:27:0)
[20:14:51.713] Timer tick, asking Buildbucket for the build status
[20:14:51.956] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:51.956] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:28:0) after 1m0s
[20:15:51.983] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:28:0)
[20:15:51.983] Timer tick, asking Buildbucket for the build status
[20:15:52.063] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:52.063] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:29:0) after 1m0s
[20:16:52.152] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:29:0)
[20:16:52.152] Timer tick, asking Buildbucket for the build status
[20:16:52.233] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:52.233] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:30:0) after 1m0s
[20:17:52.414] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:30:0)
[20:17:52.414] Timer tick, asking Buildbucket for the build status
[20:17:52.527] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:52.527] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:31:0) after 1m0s
[20:18:52.653] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:31:0)
[20:18:52.653] Timer tick, asking Buildbucket for the build status
[20:18:52.958] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:52.959] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:32:0) after 1m0s
[20:19:53.024] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:32:0)
[20:19:53.024] Timer tick, asking Buildbucket for the build status
[20:19:53.383] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:53.383] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:33:0) after 1m0s
[20:20:53.375] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:33:0)
[20:20:53.376] Timer tick, asking Buildbucket for the build status
[20:20:53.443] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:53.443] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:34:0) after 1m0s
[20:21:53.443] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:34:0)
[20:21:53.443] Timer tick, asking Buildbucket for the build status
[20:21:53.511] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:53.511] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:35:0) after 1m0s
[20:22:53.550] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:35:0)
[20:22:53.550] Timer tick, asking Buildbucket for the build status
[20:22:53.615] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:53.615] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:36:0) after 1m0s
[20:23:53.719] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:36:0)
[20:23:53.719] Timer tick, asking Buildbucket for the build status
[20:23:53.864] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:53.864] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:37:0) after 1m0s
[20:24:53.888] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:37:0)
[20:24:53.888] Timer tick, asking Buildbucket for the build status
[20:24:54.262] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:54.262] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:38:0) after 1m0s
[20:25:54.433] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:38:0)
[20:25:54.434] Timer tick, asking Buildbucket for the build status
[20:25:54.495] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:54.495] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:39:0) after 1m0s
[20:26:54.633] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:39:0)
[20:26:54.633] Timer tick, asking Buildbucket for the build status
[20:26:54.721] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:54.721] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:40:0) after 1m0s
[20:27:54.951] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:40:0)
[20:27:54.951] Timer tick, asking Buildbucket for the build status
[20:27:55.154] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:55.154] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:41:0) after 1m0s
[20:28:55.141] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:41:0)
[20:28:55.141] Timer tick, asking Buildbucket for the build status
[20:28:55.352] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:55.352] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:42:0) after 1m0s
[20:29:55.497] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:42:0)
[20:29:55.497] Timer tick, asking Buildbucket for the build status
[20:29:55.647] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:55.647] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:43:0) after 1m0s
[20:30:55.731] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:43:0)
[20:30:55.731] Timer tick, asking Buildbucket for the build status
[20:30:55.810] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:55.810] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:44:0) after 1m0s
[20:31:55.837] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:44:0)
[20:31:55.837] Timer tick, asking Buildbucket for the build status
[20:31:55.902] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:55.902] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:45:0) after 1m0s
[20:32:55.970] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:45:0)
[20:32:55.970] Timer tick, asking Buildbucket for the build status
[20:32:56.058] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:56.058] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:46:0) after 1m0s
[20:33:56.115] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:46:0)
[20:33:56.115] Timer tick, asking Buildbucket for the build status
[20:33:56.272] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:56.272] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:47:0) after 1m0s
[20:34:56.382] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:47:0)
[20:34:56.382] Timer tick, asking Buildbucket for the build status
[20:34:56.535] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:56.535] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:48:0) after 1m0s
[20:35:56.577] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:48:0)
[20:35:56.577] Timer tick, asking Buildbucket for the build status
[20:35:56.995] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:56.995] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:49:0) after 1m0s
[20:36:57.109] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:49:0)
[20:36:57.109] Timer tick, asking Buildbucket for the build status
[20:36:57.219] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:57.219] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:50:0) after 1m0s
[20:37:57.269] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:50:0)
[20:37:57.269] Timer tick, asking Buildbucket for the build status
[20:37:57.342] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:57.342] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:51:0) after 1m0s
[20:38:57.425] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:51:0)
[20:38:57.425] Timer tick, asking Buildbucket for the build status
[20:38:57.776] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:57.776] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:52:0) after 1m0s
[20:39:57.817] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:52:0)
[20:39:57.817] Timer tick, asking Buildbucket for the build status
[20:39:58.059] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:58.059] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:53:0) after 1m0s
[20:40:58.140] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:53:0)
[20:40:58.140] Timer tick, asking Buildbucket for the build status
[20:40:58.251] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:58.251] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:54:0) after 1m0s
[20:41:58.593] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:54:0)
[20:41:58.593] Timer tick, asking Buildbucket for the build status
[20:41:58.984] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:58.984] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:55:0) after 1m0s
[20:42:59.224] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:55:0)
[20:42:59.224] Timer tick, asking Buildbucket for the build status
[20:42:59.344] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:59.344] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:56:0) after 1m0s
[20:43:59.398] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:56:0)
[20:43:59.398] Timer tick, asking Buildbucket for the build status
[20:43:59.505] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:59.505] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:57:0) after 1m0s
[20:44:59.595] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:57:0)
[20:44:59.595] Timer tick, asking Buildbucket for the build status
[20:44:59.932] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:59.932] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:58:0) after 1m0s
[20:46:00.072] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:58:0)
[20:46:00.072] Timer tick, asking Buildbucket for the build status
[20:46:00.304] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:00.304] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:59:0) after 1m0s
[20:47:00.327] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:59:0)
[20:47:00.327] Timer tick, asking Buildbucket for the build status
[20:47:00.630] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:00.630] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:60:0) after 1m0s
[20:48:00.730] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:60:0)
[20:48:00.730] Timer tick, asking Buildbucket for the build status
[20:48:00.937] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:00.937] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:61:0) after 1m0s
[20:49:02.854] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:61:0)
[20:49:02.854] Timer tick, asking Buildbucket for the build status
[20:49:03.209] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:03.209] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:62:0) after 1m0s
[20:50:03.504] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:62:0)
[20:50:03.504] Timer tick, asking Buildbucket for the build status
[20:50:03.798] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:03.798] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:63:0) after 1m0s
[20:51:03.865] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:63:0)
[20:51:03.865] Timer tick, asking Buildbucket for the build status
[20:51:03.977] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:03.977] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:64:0) after 1m0s
[20:52:04.030] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:64:0)
[20:52:04.030] Timer tick, asking Buildbucket for the build status
[20:52:04.591] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:04.591] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:65:0) after 1m0s
[20:53:04.623] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:65:0)
[20:53:04.623] Timer tick, asking Buildbucket for the build status
[20:53:05.079] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:05.079] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:66:0) after 1m0s
[20:54:05.128] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:66:0)
[20:54:05.128] Timer tick, asking Buildbucket for the build status
[20:54:05.212] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:05.212] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:67:0) after 1m0s
[20:55:05.387] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:67:0)
[20:55:05.387] Timer tick, asking Buildbucket for the build status
[20:55:05.575] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:05.575] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:68:0) after 1m0s
[20:56:05.830] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:68:0)
[20:56:05.830] Timer tick, asking Buildbucket for the build status
[20:56:05.914] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:05.914] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:69:0) after 1m0s
[20:57:05.948] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:69:0)
[20:57:05.948] Timer tick, asking Buildbucket for the build status
[20:57:06.138] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:06.138] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:70:0) after 1m0s
[20:58:06.262] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:70:0)
[20:58:06.262] Timer tick, asking Buildbucket for the build status
[20:58:06.388] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:06.388] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:71:0) after 1m0s
[20:59:06.717] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:71:0)
[20:59:06.717] Timer tick, asking Buildbucket for the build status
[20:59:06.853] Build 8861815289713076464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:06.857] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9027245351893458592:72:0) after 1m0s
[20:59:20.985] Received PubSub notification, asking Buildbucket for the build status
[20:59:21.071] Build 8861815289713076464: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[20:59:21.071] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607115560272297",
"created_by": "project:chromium",
"created_ts": "1607111383672996",
"failure_reason": "BUILD_FAILURE",
"id": "8861815289713076464",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3883710078f4fa58b1ce1f2230d8e3b96241c243\"}}",
"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- WebContentsImplBrowserTest.PropagateFullscreenOptions\"}, \"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\": \"3883710078f4fa58b1ce1f2230d8e3b96241c243\", \"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-central1-b-8-4-jnw0\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.fyi\", \"buildername\": \"Linux Viz\", \"buildnumber\": 25176, \"got_angle_revision\": \"51a0e95d78eb22b1d87dfe7cb10a576690931986\", \"got_dawn_revision\": \"f31b78e90e1ea17bf012edd22d2f35e9d89adb15\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"3883710078f4fa58b1ce1f2230d8e3b96241c243\", \"got_revision_cp\": \"refs/heads/master@{#833811}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"bae138de13ac876cbca0e30a40b89dc808e2b29d\", \"got_v8_revision\": \"61f3e61408129afc0d9e1268d3354a283ea3c881\", \"got_v8_revision_cp\": \"refs/heads/8.9.100@{#1}\", \"got_webrtc_revision\": \"6c80aebd00360ee00c84c7b14422c5fe475d8916\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32774}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.linux:linux-viz-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3883710078f4fa58b1ce1f2230d8e3b96241c243\", \"swarm_hashes_refs/heads/master(at){#833811}_without_patch\": {\"blink_web_tests\": \"03944ff17c524b6e2a491f58be2023d635fdf60d\", \"browser_tests\": \"a4f8bd5af2365bb452e1f79e751c5811a7e51d73\", \"content_browsertests\": \"85e3133f3aecc3078166c305b882b1ec1cb042ec\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0e22c04085d4f23fdc95f18e428a3d660f7cf1c501a8c991d94e3298ba2ece61_v2\", \"builder_5e40e9df3c25d6ec04b042625e64587de25b7cc9257c9b922c4342550ccccbb8_v2\", \"builder_ce22d0fc5c297d03141230de59f52ebaf9005395a667e8b14df59e499796620f_v2\", \"builder_d2010e0a6a440fb89731b061caf7d596c92723d209ffefd62489cc037db4f7a7_v2\", \"builder_f5b4454e99a04aaa5ba743b59cc70de19e20bebedef65e50e3b90534a8d6a5e4_v2\", \"builder_fa0031875b7f2f11c46443055ab400bfaeb07a1d2a142b58c631314016c04e0a_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-central1-b-8-4-jnw0\"], \"image\": [\"chrome-xenial-20112900-2f7c54bbcac\"], \"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\": [\"5640-d0eb544\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**skia_renderer_content_browsertests** failed because of:\\n\\n- WebContentsImplBrowserTest.PropagateFullscreenOptions\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1607111540049738",
"status": "COMPLETED",
"status_changed_ts": "1607111541629338",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/25176",
"builder:Linux Viz",
"buildset:commit/git/3883710078f4fa58b1ce1f2230d8e3b96241c243",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3883710078f4fa58b1ce1f2230d8e3b96241c243",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027245351893458592",
"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/8861815289713076464/+/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:50481f2502c68d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1607115560600090",
"url": "https://ci.chromium.org/b/8861815289713076464",
"utcnow_ts": "1607115561061691"
}
}
[20:59:21.072] Invocation finished in 1h9m38.862417047s with status FAILED