[13:41:10.333] New invocation is queued and will start shortly
[13:41:11.415] Starting the invocation (attempt 1)
[13:41:11.492] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:41:11.492] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:41:11.492] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029986446887142192",
"parameters_json": "{\"builder_name\":\"Linux Viz\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"2d8061fce5627e297adbc2af549ea8df8f47f647\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Viz",
"scheduler_invocation_id:9029986446887142192",
"scheduler_job_id:chromium/Linux Viz",
"user_agent:luci-scheduler",
"buildset:commit/git/2d8061fce5627e297adbc2af549ea8df8f47f647",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2d8061fce5627e297adbc2af549ea8df8f47f647",
"gitiles_ref:refs/heads/master"
]
}
[13:41:12.253] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604497271608169",
"id": "8864556384885029792",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2d8061fce5627e297adbc2af549ea8df8f47f647\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604497272141995",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/24607",
"builder:Linux Viz",
"buildset:commit/git/2d8061fce5627e297adbc2af549ea8df8f47f647",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2d8061fce5627e297adbc2af549ea8df8f47f647",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029986446887142192",
"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/8864556384885029792/+/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": "1604497272142107",
"url": "https://ci.chromium.org/b/8864556384885029792",
"utcnow_ts": "1604497272244677"
}
}
[13:41:12.253] Task URL: https://ci.chromium.org/b/8864556384885029792
[13:41:12.253] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:2:0) after 1m0s
[13:41:17.893] Received PubSub notification, asking Buildbucket for the build status
[13:41:17.958] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:12.277] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:2:0)
[13:42:12.277] Timer tick, asking Buildbucket for the build status
[13:42:12.510] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:12.510] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:4:0) after 1m0s
[13:43:12.551] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:4:0)
[13:43:12.551] Timer tick, asking Buildbucket for the build status
[13:43:13.143] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:13.143] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:5:0) after 1m0s
[13:44:13.152] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:5:0)
[13:44:13.152] Timer tick, asking Buildbucket for the build status
[13:44:13.496] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:13.496] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:6:0) after 1m0s
[13:45:13.648] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:6:0)
[13:45:13.648] Timer tick, asking Buildbucket for the build status
[13:45:13.785] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:13.785] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:7:0) after 1m0s
[13:46:13.830] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:7:0)
[13:46:13.830] Timer tick, asking Buildbucket for the build status
[13:46:14.081] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:14.081] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:8:0) after 1m0s
[13:47:14.147] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:8:0)
[13:47:14.147] Timer tick, asking Buildbucket for the build status
[13:47:15.124] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:15.124] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:9:0) after 1m0s
[13:48:15.182] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:9:0)
[13:48:15.182] Timer tick, asking Buildbucket for the build status
[13:48:15.292] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:15.292] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:10:0) after 1m0s
[13:49:15.470] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:10:0)
[13:49:15.470] Timer tick, asking Buildbucket for the build status
[13:49:15.541] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:15.541] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:11:0) after 1m0s
[13:50:15.757] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:11:0)
[13:50:15.757] Timer tick, asking Buildbucket for the build status
[13:50:15.880] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:15.880] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:12:0) after 1m0s
[13:51:15.972] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:12:0)
[13:51:15.972] Timer tick, asking Buildbucket for the build status
[13:51:16.039] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:16.039] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:13:0) after 1m0s
[13:52:16.126] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:13:0)
[13:52:16.126] Timer tick, asking Buildbucket for the build status
[13:52:16.851] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:16.851] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:14:0) after 1m0s
[13:53:16.967] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:14:0)
[13:53:16.967] Timer tick, asking Buildbucket for the build status
[13:53:17.086] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:17.086] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:15:0) after 1m0s
[13:54:17.892] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:15:0)
[13:54:17.892] Timer tick, asking Buildbucket for the build status
[13:54:17.953] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:17.953] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:16:0) after 1m0s
[13:55:18.722] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:16:0)
[13:55:18.722] Timer tick, asking Buildbucket for the build status
[13:55:18.966] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:18.966] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:17:0) after 1m0s
[13:56:19.101] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:17:0)
[13:56:19.101] Timer tick, asking Buildbucket for the build status
[13:56:19.201] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:19.201] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:18:0) after 1m0s
[13:57:19.200] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:18:0)
[13:57:19.200] Timer tick, asking Buildbucket for the build status
[13:57:19.257] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:19.257] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:19:0) after 1m0s
[13:58:19.319] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:19:0)
[13:58:19.319] Timer tick, asking Buildbucket for the build status
[13:58:19.493] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:19.493] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:20:0) after 1m0s
[13:59:19.518] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:20:0)
[13:59:19.518] Timer tick, asking Buildbucket for the build status
[13:59:20.949] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:20.949] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:21:0) after 1m0s
[14:00:21.017] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:21:0)
[14:00:21.017] Timer tick, asking Buildbucket for the build status
[14:00:21.092] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:21.092] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:22:0) after 1m0s
[14:01:21.188] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:22:0)
[14:01:21.188] Timer tick, asking Buildbucket for the build status
[14:01:21.369] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:21.369] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:23:0) after 1m0s
[14:02:21.435] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:23:0)
[14:02:21.435] Timer tick, asking Buildbucket for the build status
[14:02:21.527] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:21.527] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:24:0) after 1m0s
[14:03:21.554] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:24:0)
[14:03:21.554] Timer tick, asking Buildbucket for the build status
[14:03:21.909] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:21.910] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:25:0) after 1m0s
[14:04:21.949] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:25:0)
[14:04:21.949] Timer tick, asking Buildbucket for the build status
[14:04:22.054] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:22.054] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:26:0) after 1m0s
[14:05:22.046] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:26:0)
[14:05:22.046] Timer tick, asking Buildbucket for the build status
[14:05:22.682] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:22.682] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:27:0) after 1m0s
[14:06:22.780] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:27:0)
[14:06:22.780] Timer tick, asking Buildbucket for the build status
[14:06:22.903] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:22.903] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:28:0) after 1m0s
[14:07:22.983] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:28:0)
[14:07:22.983] Timer tick, asking Buildbucket for the build status
[14:07:23.124] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:23.124] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:29:0) after 1m0s
[14:08:23.312] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:29:0)
[14:08:23.312] Timer tick, asking Buildbucket for the build status
[14:08:23.492] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:23.492] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:30:0) after 1m0s
[14:09:23.521] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:30:0)
[14:09:23.521] Timer tick, asking Buildbucket for the build status
[14:09:23.847] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:23.847] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:31:0) after 1m0s
[14:10:23.911] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:31:0)
[14:10:23.911] Timer tick, asking Buildbucket for the build status
[14:10:25.148] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:25.148] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:32:0) after 1m0s
[14:11:25.274] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:32:0)
[14:11:25.274] Timer tick, asking Buildbucket for the build status
[14:11:25.493] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:25.493] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:33:0) after 1m0s
[14:12:25.597] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:33:0)
[14:12:25.597] Timer tick, asking Buildbucket for the build status
[14:12:25.674] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:25.674] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:34:0) after 1m0s
[14:13:25.797] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:34:0)
[14:13:25.797] Timer tick, asking Buildbucket for the build status
[14:13:25.860] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:25.860] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:35:0) after 1m0s
[14:14:26.164] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:35:0)
[14:14:26.164] Timer tick, asking Buildbucket for the build status
[14:14:26.233] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:26.233] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:36:0) after 1m0s
[14:15:26.307] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:36:0)
[14:15:26.307] Timer tick, asking Buildbucket for the build status
[14:15:30.050] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:30.050] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:37:0) after 1m0s
[14:16:30.137] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:37:0)
[14:16:30.137] Timer tick, asking Buildbucket for the build status
[14:16:30.354] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:30.354] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:38:0) after 1m0s
[14:17:30.427] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:38:0)
[14:17:30.427] Timer tick, asking Buildbucket for the build status
[14:17:30.498] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:30.498] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:39:0) after 1m0s
[14:18:30.529] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:39:0)
[14:18:30.529] Timer tick, asking Buildbucket for the build status
[14:18:30.593] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:30.593] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:40:0) after 1m0s
[14:19:30.680] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:40:0)
[14:19:30.680] Timer tick, asking Buildbucket for the build status
[14:19:30.752] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:30.752] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:41:0) after 1m0s
[14:20:30.903] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:41:0)
[14:20:30.903] Timer tick, asking Buildbucket for the build status
[14:20:31.476] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:31.476] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:42:0) after 1m0s
[14:21:31.451] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:42:0)
[14:21:31.451] Timer tick, asking Buildbucket for the build status
[14:21:31.574] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:31.574] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:43:0) after 1m0s
[14:22:31.628] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:43:0)
[14:22:31.628] Timer tick, asking Buildbucket for the build status
[14:22:31.842] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:31.842] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:44:0) after 1m0s
[14:23:31.867] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:44:0)
[14:23:31.867] Timer tick, asking Buildbucket for the build status
[14:23:32.317] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:32.317] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:45:0) after 1m0s
[14:24:32.343] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:45:0)
[14:24:32.343] Timer tick, asking Buildbucket for the build status
[14:24:32.430] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:32.430] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:46:0) after 1m0s
[14:25:32.508] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:46:0)
[14:25:32.508] Timer tick, asking Buildbucket for the build status
[14:25:32.649] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:32.649] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:47:0) after 1m0s
[14:26:32.707] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:47:0)
[14:26:32.707] Timer tick, asking Buildbucket for the build status
[14:26:32.836] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:32.836] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:48:0) after 1m0s
[14:27:32.883] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:48:0)
[14:27:32.883] Timer tick, asking Buildbucket for the build status
[14:27:33.527] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:33.527] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:49:0) after 1m0s
[14:28:33.662] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:49:0)
[14:28:33.662] Timer tick, asking Buildbucket for the build status
[14:28:33.722] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:33.722] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:50:0) after 1m0s
[14:29:33.793] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:50:0)
[14:29:33.793] Timer tick, asking Buildbucket for the build status
[14:29:33.869] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:33.869] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:51:0) after 1m0s
[14:30:33.912] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:51:0)
[14:30:33.912] Timer tick, asking Buildbucket for the build status
[14:30:33.995] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:33.995] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:52:0) after 1m0s
[14:31:34.083] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:52:0)
[14:31:34.083] Timer tick, asking Buildbucket for the build status
[14:31:34.761] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:34.761] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:53:0) after 1m0s
[14:32:34.837] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:53:0)
[14:32:34.837] Timer tick, asking Buildbucket for the build status
[14:32:35.124] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:35.124] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:54:0) after 1m0s
[14:33:35.151] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:54:0)
[14:33:35.151] Timer tick, asking Buildbucket for the build status
[14:33:35.248] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:35.249] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:55:0) after 1m0s
[14:34:35.317] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:55:0)
[14:34:35.317] Timer tick, asking Buildbucket for the build status
[14:34:35.386] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:35.386] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:56:0) after 1m0s
[14:35:35.444] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:56:0)
[14:35:35.444] Timer tick, asking Buildbucket for the build status
[14:35:35.522] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:35.522] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:57:0) after 1m0s
[14:36:35.547] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:57:0)
[14:36:35.547] Timer tick, asking Buildbucket for the build status
[14:36:35.656] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:35.656] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:58:0) after 1m0s
[14:37:35.666] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:58:0)
[14:37:35.666] Timer tick, asking Buildbucket for the build status
[14:37:35.824] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:35.824] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:59:0) after 1m0s
[14:38:35.870] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:59:0)
[14:38:35.870] Timer tick, asking Buildbucket for the build status
[14:38:36.041] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:36.041] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:60:0) after 1m0s
[14:39:36.098] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:60:0)
[14:39:36.098] Timer tick, asking Buildbucket for the build status
[14:39:36.194] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:36.194] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:61:0) after 1m0s
[14:40:36.244] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:61:0)
[14:40:36.244] Timer tick, asking Buildbucket for the build status
[14:40:36.317] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:36.317] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:62:0) after 1m0s
[14:41:36.342] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:62:0)
[14:41:36.342] Timer tick, asking Buildbucket for the build status
[14:41:36.454] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:36.454] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:63:0) after 1m0s
[14:42:36.460] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:63:0)
[14:42:36.460] Timer tick, asking Buildbucket for the build status
[14:42:36.687] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:36.687] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:64:0) after 1m0s
[14:43:36.782] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:64:0)
[14:43:36.782] Timer tick, asking Buildbucket for the build status
[14:43:37.326] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:37.326] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:65:0) after 1m0s
[14:44:37.423] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:65:0)
[14:44:37.423] Timer tick, asking Buildbucket for the build status
[14:44:37.479] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:37.479] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:66:0) after 1m0s
[14:45:37.547] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:66:0)
[14:45:37.547] Timer tick, asking Buildbucket for the build status
[14:45:37.759] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:37.760] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:67:0) after 1m0s
[14:46:37.899] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:67:0)
[14:46:37.899] Timer tick, asking Buildbucket for the build status
[14:46:38.017] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:38.017] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:68:0) after 1m0s
[14:47:38.085] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:68:0)
[14:47:38.085] Timer tick, asking Buildbucket for the build status
[14:47:38.158] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:38.158] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:69:0) after 1m0s
[14:48:38.245] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:69:0)
[14:48:38.245] Timer tick, asking Buildbucket for the build status
[14:48:38.355] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:38.356] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:70:0) after 1m0s
[14:49:38.379] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:70:0)
[14:49:38.379] Timer tick, asking Buildbucket for the build status
[14:49:38.446] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:38.446] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:71:0) after 1m0s
[14:50:38.470] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:71:0)
[14:50:38.470] Timer tick, asking Buildbucket for the build status
[14:50:38.578] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:38.578] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:72:0) after 1m0s
[14:51:38.602] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:72:0)
[14:51:38.602] Timer tick, asking Buildbucket for the build status
[14:51:39.042] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:39.042] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:73:0) after 1m0s
[14:52:39.073] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:73:0)
[14:52:39.073] Timer tick, asking Buildbucket for the build status
[14:52:39.176] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:39.176] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:74:0) after 1m0s
[14:53:39.257] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:74:0)
[14:53:39.257] Timer tick, asking Buildbucket for the build status
[14:53:39.371] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:39.371] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:75:0) after 1m0s
[14:54:39.410] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:75:0)
[14:54:39.410] Timer tick, asking Buildbucket for the build status
[14:54:39.516] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:39.516] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:76:0) after 1m0s
[14:55:39.561] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:76:0)
[14:55:39.561] Timer tick, asking Buildbucket for the build status
[14:55:39.634] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:39.634] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:77:0) after 1m0s
[14:56:39.754] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:77:0)
[14:56:39.754] Timer tick, asking Buildbucket for the build status
[14:56:39.863] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:39.863] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:78:0) after 1m0s
[14:57:39.956] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:78:0)
[14:57:39.956] Timer tick, asking Buildbucket for the build status
[14:57:40.016] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:40.016] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:79:0) after 1m0s
[14:58:40.148] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:79:0)
[14:58:40.148] Timer tick, asking Buildbucket for the build status
[14:58:40.406] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:40.406] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:80:0) after 1m0s
[14:59:40.468] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:80:0)
[14:59:40.468] Timer tick, asking Buildbucket for the build status
[14:59:40.541] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:40.541] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:81:0) after 1m0s
[15:00:40.549] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:81:0)
[15:00:40.549] Timer tick, asking Buildbucket for the build status
[15:00:40.639] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:40.640] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:82:0) after 1m0s
[15:01:40.738] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:82:0)
[15:01:40.738] Timer tick, asking Buildbucket for the build status
[15:01:42.062] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:42.062] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:83:0) after 1m0s
[15:02:42.089] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:83:0)
[15:02:42.089] Timer tick, asking Buildbucket for the build status
[15:02:42.168] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:42.168] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:84:0) after 1m0s
[15:03:42.251] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:84:0)
[15:03:42.251] Timer tick, asking Buildbucket for the build status
[15:03:42.322] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:42.322] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:85:0) after 1m0s
[15:04:42.332] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:85:0)
[15:04:42.332] Timer tick, asking Buildbucket for the build status
[15:04:42.457] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:42.458] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:86:0) after 1m0s
[15:05:42.489] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:86:0)
[15:05:42.489] Timer tick, asking Buildbucket for the build status
[15:05:42.729] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:42.730] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:87:0) after 1m0s
[15:06:42.737] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:87:0)
[15:06:42.737] Timer tick, asking Buildbucket for the build status
[15:06:42.860] Build 8864556384885029792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:42.860] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9029986446887142192:88:0) after 1m0s
[15:07:14.045] Received PubSub notification, asking Buildbucket for the build status
[15:07:15.199] Build 8864556384885029792: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:07:15.199] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604502433373023",
"created_by": "project:chromium",
"created_ts": "1604497271608169",
"id": "8864556384885029792",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2d8061fce5627e297adbc2af549ea8df8f47f647\"}}",
"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\": \"2d8061fce5627e297adbc2af549ea8df8f47f647\", \"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-69-i4bc\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.fyi\", \"buildername\": \"Linux Viz\", \"buildnumber\": 24607, \"got_angle_revision\": \"265c5fa9a7e07ef0a0af6ed61d5682dae4fc31bd\", \"got_dawn_revision\": \"43ef0a365b8443226916c090b88b3a0b09d6df96\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"2d8061fce5627e297adbc2af549ea8df8f47f647\", \"got_revision_cp\": \"refs/heads/master@{#823962}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"19f01495a8a18f2d222c29367901c7c1045ba4a2\", \"got_v8_revision\": \"f014ab9299eed4f879b06b06873e7eac3b20b0e5\", \"got_v8_revision_cp\": \"refs/heads/8.8.227@{#1}\", \"got_webrtc_revision\": \"42cafa5696f516712bbe6709fedeedffac61544c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32540}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2d8061fce5627e297adbc2af549ea8df8f47f647\", \"swarm_hashes_refs/heads/master(at){#823962}_without_patch\": {\"blink_web_tests\": \"106cc72f74bb3e5cd7892ec6e33f52f2aa87595a\", \"browser_tests\": \"78ac0861350cbb747b47f72b36bfacc0390b578e\", \"content_browsertests\": \"d00f8e9f6b910118a39aaac62436fb4ecdbfa706\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1762153a4fb3e44e7b12a4c03db0d2d4aeadaa77fea24c2569a42ebe1b685adb_v2\", \"builder_5e40e9df3c25d6ec04b042625e64587de25b7cc9257c9b922c4342550ccccbb8_v2\", \"builder_c4692ee50382bcd35d592bcc0950e009bfc7efa8bba012f560f58cf3ab714e75_v2\", \"builder_c9091f6bc58db7983401393b6dacc93b3db8fae2c2ae9b40177b9e0904c8bc80_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-69-i4bc\"], \"image\": [\"chrome-xenial-20102500-5da31bca5f9\"], \"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\": [\"5614-d862eaa\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1604497276391464",
"status": "COMPLETED",
"status_changed_ts": "1604502433703974",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/24607",
"builder:Linux Viz",
"buildset:commit/git/2d8061fce5627e297adbc2af549ea8df8f47f647",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2d8061fce5627e297adbc2af549ea8df8f47f647",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029986446887142192",
"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/8864556384885029792/+/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:4fac4efc559a1c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1604502433709442",
"url": "https://ci.chromium.org/b/8864556384885029792",
"utcnow_ts": "1604502435101803"
}
}
[15:07:15.199] Invocation finished in 1h26m4.884493476s with status SUCCEEDED