[06:24:35.325] New invocation is queued and will start shortly
[06:24:36.425] Starting the invocation (attempt 1)
[06:24:36.495] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:24:36.495] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:24:36.495] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026027647820197904",
"parameters_json": "{\"builder_name\":\"Linux Viz\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a542ec996fc08686f11b506cccfb2fed7267beb1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Viz",
"scheduler_invocation_id:9026027647820197904",
"scheduler_job_id:chromium/Linux Viz",
"user_agent:luci-scheduler",
"buildset:commit/git/a542ec996fc08686f11b506cccfb2fed7267beb1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a542ec996fc08686f11b506cccfb2fed7267beb1",
"gitiles_ref:refs/heads/master"
]
}
[06:24:37.128] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1608272676621758",
"id": "8860597585797755664",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a542ec996fc08686f11b506cccfb2fed7267beb1\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608272676973357",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/25404",
"builder:Linux Viz",
"buildset:commit/git/a542ec996fc08686f11b506cccfb2fed7267beb1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a542ec996fc08686f11b506cccfb2fed7267beb1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026027647820197904",
"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/8860597585797755664/+/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": "1608272676973474",
"url": "https://ci.chromium.org/b/8860597585797755664",
"utcnow_ts": "1608272677118120"
}
}
[06:24:37.128] Task URL: https://ci.chromium.org/b/8860597585797755664
[06:24:37.128] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:2:0) after 1m0s
[06:24:39.157] Received PubSub notification, asking Buildbucket for the build status
[06:24:39.223] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:37.152] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:2:0)
[06:25:37.152] Timer tick, asking Buildbucket for the build status
[06:25:37.298] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:37.298] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:4:0) after 1m0s
[06:26:37.345] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:4:0)
[06:26:37.345] Timer tick, asking Buildbucket for the build status
[06:26:37.631] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:37.631] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:5:0) after 1m0s
[06:27:37.779] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:5:0)
[06:27:37.779] Timer tick, asking Buildbucket for the build status
[06:27:38.002] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:38.002] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:6:0) after 1m0s
[06:28:38.065] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:6:0)
[06:28:38.065] Timer tick, asking Buildbucket for the build status
[06:28:38.624] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:38.624] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:7:0) after 1m0s
[06:29:38.678] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:7:0)
[06:29:38.678] Timer tick, asking Buildbucket for the build status
[06:29:38.836] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:38.836] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:8:0) after 1m0s
[06:30:38.865] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:8:0)
[06:30:38.865] Timer tick, asking Buildbucket for the build status
[06:30:38.967] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:38.967] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:9:0) after 1m0s
[06:31:39.087] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:9:0)
[06:31:39.087] Timer tick, asking Buildbucket for the build status
[06:31:39.533] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:39.533] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:10:0) after 1m0s
[06:32:39.561] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:10:0)
[06:32:39.561] Timer tick, asking Buildbucket for the build status
[06:32:39.621] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:39.621] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:11:0) after 1m0s
[06:33:39.624] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:11:0)
[06:33:39.624] Timer tick, asking Buildbucket for the build status
[06:33:39.696] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:39.696] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:12:0) after 1m0s
[06:34:39.806] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:12:0)
[06:34:39.806] Timer tick, asking Buildbucket for the build status
[06:34:39.925] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:39.925] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:13:0) after 1m0s
[06:35:40.014] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:13:0)
[06:35:40.014] Timer tick, asking Buildbucket for the build status
[06:35:40.086] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:40.086] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:14:0) after 1m0s
[06:36:40.197] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:14:0)
[06:36:40.197] Timer tick, asking Buildbucket for the build status
[06:36:40.283] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:40.283] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:15:0) after 1m0s
[06:37:40.454] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:15:0)
[06:37:40.454] Timer tick, asking Buildbucket for the build status
[06:37:40.574] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:40.574] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:16:0) after 1m0s
[06:38:40.610] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:16:0)
[06:38:40.610] Timer tick, asking Buildbucket for the build status
[06:38:40.753] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:40.753] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:17:0) after 1m0s
[06:39:40.776] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:17:0)
[06:39:40.776] Timer tick, asking Buildbucket for the build status
[06:39:41.362] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:41.362] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:18:0) after 1m0s
[06:40:41.383] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:18:0)
[06:40:41.383] Timer tick, asking Buildbucket for the build status
[06:40:41.518] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:41.518] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:19:0) after 1m0s
[06:41:41.535] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:19:0)
[06:41:41.536] Timer tick, asking Buildbucket for the build status
[06:41:41.598] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:41.598] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:20:0) after 1m0s
[06:42:41.655] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:20:0)
[06:42:41.655] Timer tick, asking Buildbucket for the build status
[06:42:41.744] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:41.744] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:21:0) after 1m0s
[06:43:41.778] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:21:0)
[06:43:41.778] Timer tick, asking Buildbucket for the build status
[06:43:41.835] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:41.835] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:22:0) after 1m0s
[06:44:41.860] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:22:0)
[06:44:41.860] Timer tick, asking Buildbucket for the build status
[06:44:41.984] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:41.984] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:23:0) after 1m0s
[06:45:42.054] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:23:0)
[06:45:42.054] Timer tick, asking Buildbucket for the build status
[06:45:42.147] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:42.147] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:24:0) after 1m0s
[06:46:42.206] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:24:0)
[06:46:42.206] Timer tick, asking Buildbucket for the build status
[06:46:42.360] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:42.360] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:25:0) after 1m0s
[06:47:42.384] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:25:0)
[06:47:42.384] Timer tick, asking Buildbucket for the build status
[06:47:42.639] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:42.639] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:26:0) after 1m0s
[06:48:42.707] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:26:0)
[06:48:42.707] Timer tick, asking Buildbucket for the build status
[06:48:42.798] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:42.798] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:27:0) after 1m0s
[06:49:42.828] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:27:0)
[06:49:42.828] Timer tick, asking Buildbucket for the build status
[06:49:42.953] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:42.953] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:28:0) after 1m0s
[06:50:43.009] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:28:0)
[06:50:43.009] Timer tick, asking Buildbucket for the build status
[06:50:43.150] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:43.150] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:29:0) after 1m0s
[06:51:43.166] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:29:0)
[06:51:43.166] Timer tick, asking Buildbucket for the build status
[06:51:43.252] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:43.253] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:30:0) after 1m0s
[06:52:43.309] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:30:0)
[06:52:43.309] Timer tick, asking Buildbucket for the build status
[06:52:43.383] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:43.383] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:31:0) after 1m0s
[06:53:43.457] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:31:0)
[06:53:43.457] Timer tick, asking Buildbucket for the build status
[06:53:43.536] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:43.536] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:32:0) after 1m0s
[06:54:43.625] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:32:0)
[06:54:43.625] Timer tick, asking Buildbucket for the build status
[06:54:43.782] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:43.782] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:33:0) after 1m0s
[06:55:43.800] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:33:0)
[06:55:43.800] Timer tick, asking Buildbucket for the build status
[06:55:43.913] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:43.914] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:34:0) after 1m0s
[06:56:44.044] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:34:0)
[06:56:44.044] Timer tick, asking Buildbucket for the build status
[06:56:44.245] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:44.245] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:35:0) after 1m0s
[06:57:44.265] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:35:0)
[06:57:44.265] Timer tick, asking Buildbucket for the build status
[06:57:44.691] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:44.691] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:36:0) after 1m0s
[06:58:44.768] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:36:0)
[06:58:44.768] Timer tick, asking Buildbucket for the build status
[06:58:44.854] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:44.854] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:37:0) after 1m0s
[06:59:44.875] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:37:0)
[06:59:44.875] Timer tick, asking Buildbucket for the build status
[06:59:45.360] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:45.360] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:38:0) after 1m0s
[07:00:45.450] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:38:0)
[07:00:45.450] Timer tick, asking Buildbucket for the build status
[07:00:45.544] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:45.544] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:39:0) after 1m0s
[07:01:45.602] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:39:0)
[07:01:45.603] Timer tick, asking Buildbucket for the build status
[07:01:45.663] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:45.663] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:40:0) after 1m0s
[07:02:45.732] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:40:0)
[07:02:45.732] Timer tick, asking Buildbucket for the build status
[07:02:46.017] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:46.017] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:41:0) after 1m0s
[07:03:46.060] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:41:0)
[07:03:46.060] Timer tick, asking Buildbucket for the build status
[07:03:46.126] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:46.126] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:42:0) after 1m0s
[07:04:46.128] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:42:0)
[07:04:46.128] Timer tick, asking Buildbucket for the build status
[07:04:46.408] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:46.408] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:43:0) after 1m0s
[07:05:46.410] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:43:0)
[07:05:46.411] Timer tick, asking Buildbucket for the build status
[07:05:46.521] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:46.521] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:44:0) after 1m0s
[07:06:46.543] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:44:0)
[07:06:46.543] Timer tick, asking Buildbucket for the build status
[07:06:46.610] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:46.610] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:45:0) after 1m0s
[07:07:46.636] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:45:0)
[07:07:46.636] Timer tick, asking Buildbucket for the build status
[07:07:46.772] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:46.772] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:46:0) after 1m0s
[07:08:46.776] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:46:0)
[07:08:46.776] Timer tick, asking Buildbucket for the build status
[07:08:46.893] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:46.893] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:47:0) after 1m0s
[07:09:46.920] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:47:0)
[07:09:46.920] Timer tick, asking Buildbucket for the build status
[07:09:47.017] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:47.017] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:48:0) after 1m0s
[07:10:47.126] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:48:0)
[07:10:47.126] Timer tick, asking Buildbucket for the build status
[07:10:47.674] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:47.674] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:49:0) after 1m0s
[07:11:47.664] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:49:0)
[07:11:47.664] Timer tick, asking Buildbucket for the build status
[07:11:47.741] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:47.741] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:50:0) after 1m0s
[07:12:47.764] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:50:0)
[07:12:47.764] Timer tick, asking Buildbucket for the build status
[07:12:48.303] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:48.304] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:51:0) after 1m0s
[07:13:48.368] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:51:0)
[07:13:48.368] Timer tick, asking Buildbucket for the build status
[07:13:48.814] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:48.814] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:52:0) after 1m0s
[07:14:48.843] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:52:0)
[07:14:48.843] Timer tick, asking Buildbucket for the build status
[07:14:49.491] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:49.491] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:53:0) after 1m0s
[07:15:49.569] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:53:0)
[07:15:49.569] Timer tick, asking Buildbucket for the build status
[07:15:49.643] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:49.643] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:54:0) after 1m0s
[07:16:49.764] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:54:0)
[07:16:49.764] Timer tick, asking Buildbucket for the build status
[07:16:49.850] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:49.850] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:55:0) after 1m0s
[07:17:49.902] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:55:0)
[07:17:49.902] Timer tick, asking Buildbucket for the build status
[07:17:49.965] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:49.966] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:56:0) after 1m0s
[07:18:50.019] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:56:0)
[07:18:50.019] Timer tick, asking Buildbucket for the build status
[07:18:50.079] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:50.079] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:57:0) after 1m0s
[07:19:50.180] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:57:0)
[07:19:50.180] Timer tick, asking Buildbucket for the build status
[07:19:50.323] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:50.323] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:58:0) after 1m0s
[07:20:50.323] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:58:0)
[07:20:50.323] Timer tick, asking Buildbucket for the build status
[07:20:50.532] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:50.532] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:59:0) after 1m0s
[07:21:50.622] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:59:0)
[07:21:50.622] Timer tick, asking Buildbucket for the build status
[07:21:50.741] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:50.741] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:60:0) after 1m0s
[07:22:50.735] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:60:0)
[07:22:50.735] Timer tick, asking Buildbucket for the build status
[07:22:50.845] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:50.845] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:61:0) after 1m0s
[07:23:50.921] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:61:0)
[07:23:50.921] Timer tick, asking Buildbucket for the build status
[07:23:50.974] Build 8860597585797755664: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:50.974] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9026027647820197904:62:0) after 1m0s
[07:24:11.635] Received PubSub notification, asking Buildbucket for the build status
[07:24:11.689] Build 8860597585797755664: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:24:11.689] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1608276250995143",
"created_by": "project:chromium",
"created_ts": "1608272676621758",
"id": "8860597585797755664",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a542ec996fc08686f11b506cccfb2fed7267beb1\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"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\": \"a542ec996fc08686f11b506cccfb2fed7267beb1\", \"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-159-we0k\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.fyi\", \"buildername\": \"Linux Viz\", \"buildnumber\": 25404, \"got_angle_revision\": \"c47b951ecbcbbba653db801bc7eb5f4fa5d72042\", \"got_dawn_revision\": \"ee977a0df8e3f3a12a6f2983db98838bf65566da\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"a542ec996fc08686f11b506cccfb2fed7267beb1\", \"got_revision_cp\": \"refs/heads/master@{#838424}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"af6738a7d0a2708809a9c21e9dacc6fcface7939\", \"got_v8_revision_cp\": \"refs/heads/8.9.173@{#1}\", \"got_webrtc_revision\": \"8592111e458d253e53806db65d23d21bfe9d9db4\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32849}\", \"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\": \"a542ec996fc08686f11b506cccfb2fed7267beb1\", \"swarm_hashes_refs/heads/master(at){#838424}_without_patch\": {\"blink_web_tests\": \"ce73455633f05484158b75651e6dd8bd46c08b21\", \"browser_tests\": \"b3509602e89eb3a3a7a5a3a52575984f7a527a23\", \"content_browsertests\": \"212716bc89ac0542d1f2445f5696722e2445520c\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_5e40e9df3c25d6ec04b042625e64587de25b7cc9257c9b922c4342550ccccbb8_v2\", \"builder_be6033c31ad7fe993e51aceefec32c9781d9cb2aa7f3fe71df24045d7e98805a_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-159-we0k\"], \"image\": [\"chrome-xenial-20120600-c5cfbe11ce8\"], \"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\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1608272678604360",
"status": "COMPLETED",
"status_changed_ts": "1608276251377666",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/25404",
"builder:Linux Viz",
"buildset:commit/git/a542ec996fc08686f11b506cccfb2fed7267beb1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a542ec996fc08686f11b506cccfb2fed7267beb1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026027647820197904",
"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/8860597585797755664/+/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:508d5712a5248c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1608276251383896",
"url": "https://ci.chromium.org/b/8860597585797755664",
"utcnow_ts": "1608276251682622"
}
}
[07:24:11.689] Invocation finished in 59m36.380733836s with status SUCCEEDED