[12:07:28.158] New invocation is queued and will start shortly
[12:07:29.249] Starting the invocation (attempt 1)
[12:07:29.288] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:07:29.288] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:07:29.288] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023106972716053184",
"parameters_json": "{\"builder_name\":\"Linux Viz\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4ce9888371cba53fc21702714881d10df2be23ec\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Viz",
"scheduler_invocation_id:9023106972716053184",
"scheduler_job_id:chromium/Linux Viz",
"user_agent:luci-scheduler",
"buildset:commit/git/4ce9888371cba53fc21702714881d10df2be23ec",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4ce9888371cba53fc21702714881d10df2be23ec",
"gitiles_ref:refs/heads/master"
]
}
[12:07:30.173] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611058049383007",
"id": "8857676910768570112",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4ce9888371cba53fc21702714881d10df2be23ec\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611058049971515",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/25945",
"builder:Linux Viz",
"buildset:commit/git/4ce9888371cba53fc21702714881d10df2be23ec",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4ce9888371cba53fc21702714881d10df2be23ec",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023106972716053184",
"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/8857676910768570112/+/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": "1611058049971637",
"url": "https://ci.chromium.org/b/8857676910768570112",
"utcnow_ts": "1611058050164642"
}
}
[12:07:30.173] Task URL: https://ci.chromium.org/b/8857676910768570112
[12:07:30.173] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:2:0) after 1m0s
[12:07:33.310] Received PubSub notification, asking Buildbucket for the build status
[12:07:33.521] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:30.235] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:2:0)
[12:08:30.235] Timer tick, asking Buildbucket for the build status
[12:08:30.325] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:30.325] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:4:0) after 1m0s
[12:09:30.417] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:4:0)
[12:09:30.417] Timer tick, asking Buildbucket for the build status
[12:09:30.620] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:30.620] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:5:0) after 1m0s
[12:10:30.718] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:5:0)
[12:10:30.718] Timer tick, asking Buildbucket for the build status
[12:10:30.902] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:30.902] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:6:0) after 1m0s
[12:11:31.005] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:6:0)
[12:11:31.005] Timer tick, asking Buildbucket for the build status
[12:11:31.138] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:31.138] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:7:0) after 1m0s
[12:12:34.052] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:7:0)
[12:12:34.052] Timer tick, asking Buildbucket for the build status
[12:12:34.183] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:34.183] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:8:0) after 1m0s
[12:13:34.209] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:8:0)
[12:13:34.209] Timer tick, asking Buildbucket for the build status
[12:13:34.412] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:34.412] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:9:0) after 1m0s
[12:14:34.459] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:9:0)
[12:14:34.459] Timer tick, asking Buildbucket for the build status
[12:14:34.703] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:34.703] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:10:0) after 1m0s
[12:15:34.774] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:10:0)
[12:15:34.774] Timer tick, asking Buildbucket for the build status
[12:15:34.849] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:34.849] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:11:0) after 1m0s
[12:16:34.890] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:11:0)
[12:16:34.890] Timer tick, asking Buildbucket for the build status
[12:16:34.968] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:34.968] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:12:0) after 1m0s
[12:17:35.043] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:12:0)
[12:17:35.043] Timer tick, asking Buildbucket for the build status
[12:17:35.146] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:35.146] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:13:0) after 1m0s
[12:18:35.198] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:13:0)
[12:18:35.198] Timer tick, asking Buildbucket for the build status
[12:18:35.290] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:35.290] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:14:0) after 1m0s
[12:19:35.332] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:14:0)
[12:19:35.332] Timer tick, asking Buildbucket for the build status
[12:19:35.434] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:35.434] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:15:0) after 1m0s
[12:20:35.499] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:15:0)
[12:20:35.499] Timer tick, asking Buildbucket for the build status
[12:20:35.593] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:35.593] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:16:0) after 1m0s
[12:21:35.625] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:16:0)
[12:21:35.625] Timer tick, asking Buildbucket for the build status
[12:21:35.691] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:35.691] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:17:0) after 1m0s
[12:22:35.714] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:17:0)
[12:22:35.714] Timer tick, asking Buildbucket for the build status
[12:22:35.809] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:35.809] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:18:0) after 1m0s
[12:23:35.838] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:18:0)
[12:23:35.838] Timer tick, asking Buildbucket for the build status
[12:23:35.893] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:35.893] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:19:0) after 1m0s
[12:24:35.992] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:19:0)
[12:24:35.992] Timer tick, asking Buildbucket for the build status
[12:24:36.055] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:36.055] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:20:0) after 1m0s
[12:25:36.186] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:20:0)
[12:25:36.186] Timer tick, asking Buildbucket for the build status
[12:25:36.566] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:36.566] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:21:0) after 1m0s
[12:26:36.619] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:21:0)
[12:26:36.619] Timer tick, asking Buildbucket for the build status
[12:26:36.689] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:36.689] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:22:0) after 1m0s
[12:27:36.785] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:22:0)
[12:27:36.785] Timer tick, asking Buildbucket for the build status
[12:27:36.909] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:36.909] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:23:0) after 1m0s
[12:28:36.900] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:23:0)
[12:28:36.900] Timer tick, asking Buildbucket for the build status
[12:28:37.266] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:37.266] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:24:0) after 1m0s
[12:29:37.331] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:24:0)
[12:29:37.331] Timer tick, asking Buildbucket for the build status
[12:29:37.397] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:37.397] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:25:0) after 1m0s
[12:30:37.465] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:25:0)
[12:30:37.465] Timer tick, asking Buildbucket for the build status
[12:30:37.606] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:37.606] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:26:0) after 1m0s
[12:31:37.762] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:26:0)
[12:31:37.762] Timer tick, asking Buildbucket for the build status
[12:31:37.839] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:37.839] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:27:0) after 1m0s
[12:32:37.861] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:27:0)
[12:32:37.861] Timer tick, asking Buildbucket for the build status
[12:32:38.183] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:38.183] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:28:0) after 1m0s
[12:33:38.315] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:28:0)
[12:33:38.316] Timer tick, asking Buildbucket for the build status
[12:33:38.797] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:38.797] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:29:0) after 1m0s
[12:34:38.943] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:29:0)
[12:34:38.943] Timer tick, asking Buildbucket for the build status
[12:34:39.143] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:39.143] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:30:0) after 1m0s
[12:35:39.201] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:30:0)
[12:35:39.201] Timer tick, asking Buildbucket for the build status
[12:35:39.259] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:39.259] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:31:0) after 1m0s
[12:36:39.282] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:31:0)
[12:36:39.282] Timer tick, asking Buildbucket for the build status
[12:36:39.496] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:39.496] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:32:0) after 1m0s
[12:37:39.563] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:32:0)
[12:37:39.563] Timer tick, asking Buildbucket for the build status
[12:37:39.668] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:39.668] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:33:0) after 1m0s
[12:38:39.691] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:33:0)
[12:38:39.691] Timer tick, asking Buildbucket for the build status
[12:38:39.747] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:39.747] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:34:0) after 1m0s
[12:39:39.888] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:34:0)
[12:39:39.888] Timer tick, asking Buildbucket for the build status
[12:39:39.996] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:39.996] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:35:0) after 1m0s
[12:40:40.053] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:35:0)
[12:40:40.053] Timer tick, asking Buildbucket for the build status
[12:40:40.173] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:40.173] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:36:0) after 1m0s
[12:41:40.296] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:36:0)
[12:41:40.296] Timer tick, asking Buildbucket for the build status
[12:41:40.381] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:40.381] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:37:0) after 1m0s
[12:42:40.489] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:37:0)
[12:42:40.489] Timer tick, asking Buildbucket for the build status
[12:42:40.608] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:40.609] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:38:0) after 1m0s
[12:43:40.645] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:38:0)
[12:43:40.645] Timer tick, asking Buildbucket for the build status
[12:43:40.769] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:40.769] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:39:0) after 1m0s
[12:44:40.817] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:39:0)
[12:44:40.817] Timer tick, asking Buildbucket for the build status
[12:44:41.100] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:41.100] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:40:0) after 1m0s
[12:45:41.253] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:40:0)
[12:45:41.253] Timer tick, asking Buildbucket for the build status
[12:45:41.340] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:41.340] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:41:0) after 1m0s
[12:46:41.398] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:41:0)
[12:46:41.398] Timer tick, asking Buildbucket for the build status
[12:46:41.576] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:41.576] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:42:0) after 1m0s
[12:47:41.624] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:42:0)
[12:47:41.624] Timer tick, asking Buildbucket for the build status
[12:47:41.728] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:41.728] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:43:0) after 1m0s
[12:48:41.813] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:43:0)
[12:48:41.813] Timer tick, asking Buildbucket for the build status
[12:48:41.923] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:41.923] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:44:0) after 1m0s
[12:49:42.079] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:44:0)
[12:49:42.079] Timer tick, asking Buildbucket for the build status
[12:49:42.208] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:42.208] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:45:0) after 1m0s
[12:50:42.294] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:45:0)
[12:50:42.294] Timer tick, asking Buildbucket for the build status
[12:50:42.349] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:42.349] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:46:0) after 1m0s
[12:51:42.389] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:46:0)
[12:51:42.389] Timer tick, asking Buildbucket for the build status
[12:51:42.448] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:42.448] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:47:0) after 1m0s
[12:52:42.512] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:47:0)
[12:52:42.512] Timer tick, asking Buildbucket for the build status
[12:52:42.894] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:42.894] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:48:0) after 1m0s
[12:53:42.989] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:48:0)
[12:53:42.989] Timer tick, asking Buildbucket for the build status
[12:53:43.048] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:43.048] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:49:0) after 1m0s
[12:54:43.166] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:49:0)
[12:54:43.166] Timer tick, asking Buildbucket for the build status
[12:54:43.235] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:43.235] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:50:0) after 1m0s
[12:55:43.411] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:50:0)
[12:55:43.412] Timer tick, asking Buildbucket for the build status
[12:55:43.472] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:43.472] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:51:0) after 1m0s
[12:56:43.491] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:51:0)
[12:56:43.491] Timer tick, asking Buildbucket for the build status
[12:56:43.697] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:43.697] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:52:0) after 1m0s
[12:57:43.795] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:52:0)
[12:57:43.795] Timer tick, asking Buildbucket for the build status
[12:57:43.915] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:43.915] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:53:0) after 1m0s
[12:58:43.994] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:53:0)
[12:58:43.994] Timer tick, asking Buildbucket for the build status
[12:58:44.067] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:44.067] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:54:0) after 1m0s
[12:59:44.148] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:54:0)
[12:59:44.148] Timer tick, asking Buildbucket for the build status
[12:59:44.196] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:44.196] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:55:0) after 1m0s
[13:00:44.324] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:55:0)
[13:00:44.324] Timer tick, asking Buildbucket for the build status
[13:00:44.405] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:44.405] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:56:0) after 1m0s
[13:01:44.492] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:56:0)
[13:01:44.492] Timer tick, asking Buildbucket for the build status
[13:01:44.834] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:44.834] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:57:0) after 1m0s
[13:02:44.949] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:57:0)
[13:02:44.949] Timer tick, asking Buildbucket for the build status
[13:02:46.154] Build 8857676910768570112: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:46.154] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9023106972716053184:58:0) after 1m0s
[13:03:26.271] Received PubSub notification, asking Buildbucket for the build status
[13:03:26.352] Build 8857676910768570112: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:03:26.352] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611061404918171",
"created_by": "project:chromium",
"created_ts": "1611058049383007",
"id": "8857676910768570112",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4ce9888371cba53fc21702714881d10df2be23ec\"}}",
"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\": \"4ce9888371cba53fc21702714881d10df2be23ec\", \"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-102-1pdr\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.fyi\", \"buildername\": \"Linux Viz\", \"buildnumber\": 25945, \"got_angle_revision\": \"b69f54069cbf700a4646817805ca77c0d2a01292\", \"got_dawn_revision\": \"4b316994ad01ab6b87032c5fd9b53c6d18956d8d\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"4ce9888371cba53fc21702714881d10df2be23ec\", \"got_revision_cp\": \"refs/heads/master@{#844735}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"8da0f82baf359e2b91464f111306a094f86fe71b\", \"got_v8_revision\": \"ee927aaa58e688ad6aa0256b3929c1eeea0bebd5\", \"got_v8_revision_cp\": \"refs/heads/9.0.8@{#1}\", \"got_webrtc_revision\": \"29bd8638adb0a7294f7b8ae589fdd24077539dbb\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33030}\", \"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\": \"4ce9888371cba53fc21702714881d10df2be23ec\", \"swarm_hashes_refs/heads/master(at){#844735}_without_patch\": {\"blink_web_tests\": \"b2f71111d82f8813f861596b457d89d68092adac\", \"browser_tests\": \"3ab8f4a8da31d8ad2d59e1e721423be163a12e85\", \"content_browsertests\": \"57d3dca75c5d63b9637271affcd3586fdc95f81a\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_5e40e9df3c25d6ec04b042625e64587de25b7cc9257c9b922c4342550ccccbb8_v2\", \"builder_bf60de3a2a061c3c4c67ad9afdde23d2342e42cdda6e8a4003b52312ca24fd40_v2\", \"builder_dbe717e93dfefb94e765b2da7ffc34b16e5134b01772a8d37ee52c1fe1505654_v2\", \"builder_f72b88ee7914fe5eb8b32d979ad6b47003d3a0322a10970d43d78c86a32dd14e_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-102-1pdr\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"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\": [\"5666-679c9bd\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611058052454266",
"status": "COMPLETED",
"status_changed_ts": "1611061405959651",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/25945",
"builder:Linux Viz",
"buildset:commit/git/4ce9888371cba53fc21702714881d10df2be23ec",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4ce9888371cba53fc21702714881d10df2be23ec",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023106972716053184",
"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/8857676910768570112/+/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:51335c7d5dd69d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1611061405965249",
"url": "https://ci.chromium.org/b/8857676910768570112",
"utcnow_ts": "1611061406339439"
}
}
[13:03:26.352] Invocation finished in 55m58.210460029s with status SUCCEEDED