[02:14:16.369] New invocation is queued and will start shortly
[02:14:17.477] Starting the invocation (attempt 1)
[02:14:17.520] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:14:17.520] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:14:17.521] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9024322053974059008",
"parameters_json": "{\"builder_name\":\"Linux Viz\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"dd3c4e3981ce672c863bc6a6f60c6658dbcb2188\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Viz",
"scheduler_invocation_id:9024322053974059008",
"scheduler_job_id:chromium/Linux Viz",
"user_agent:luci-scheduler",
"buildset:commit/git/dd3c4e3981ce672c863bc6a6f60c6658dbcb2188",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dd3c4e3981ce672c863bc6a6f60c6658dbcb2188",
"gitiles_ref:refs/heads/master"
]
}
[02:14:18.859] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1609899257770515",
"id": "8858891991842683776",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dd3c4e3981ce672c863bc6a6f60c6658dbcb2188\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1609899258645123",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/25735",
"builder:Linux Viz",
"buildset:commit/git/dd3c4e3981ce672c863bc6a6f60c6658dbcb2188",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dd3c4e3981ce672c863bc6a6f60c6658dbcb2188",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024322053974059008",
"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/8858891991842683776/+/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": "1609899258645351",
"url": "https://ci.chromium.org/b/8858891991842683776",
"utcnow_ts": "1609899258843136"
}
}
[02:14:18.859] Task URL: https://ci.chromium.org/b/8858891991842683776
[02:14:18.859] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:2:0) after 1m0s
[02:14:21.336] Received PubSub notification, asking Buildbucket for the build status
[02:14:21.414] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:19.094] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:2:0)
[02:15:19.094] Timer tick, asking Buildbucket for the build status
[02:15:19.265] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:19.265] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:4:0) after 1m0s
[02:16:19.344] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:4:0)
[02:16:19.344] Timer tick, asking Buildbucket for the build status
[02:16:19.476] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:19.476] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:5:0) after 1m0s
[02:17:19.586] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:5:0)
[02:17:19.586] Timer tick, asking Buildbucket for the build status
[02:17:20.112] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:20.112] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:6:0) after 1m0s
[02:18:20.174] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:6:0)
[02:18:20.174] Timer tick, asking Buildbucket for the build status
[02:18:20.321] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:20.321] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:7:0) after 1m0s
[02:19:20.341] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:7:0)
[02:19:20.341] Timer tick, asking Buildbucket for the build status
[02:19:21.029] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:21.029] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:8:0) after 1m0s
[02:20:21.015] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:8:0)
[02:20:21.015] Timer tick, asking Buildbucket for the build status
[02:20:22.138] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:22.138] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:9:0) after 1m0s
[02:21:22.205] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:9:0)
[02:21:22.205] Timer tick, asking Buildbucket for the build status
[02:21:22.312] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:22.312] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:10:0) after 1m0s
[02:22:22.385] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:10:0)
[02:22:22.385] Timer tick, asking Buildbucket for the build status
[02:22:22.572] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:22.572] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:11:0) after 1m0s
[02:23:22.606] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:11:0)
[02:23:22.606] Timer tick, asking Buildbucket for the build status
[02:23:22.679] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:22.679] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:12:0) after 1m0s
[02:24:22.701] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:12:0)
[02:24:22.702] Timer tick, asking Buildbucket for the build status
[02:24:22.829] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:22.829] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:13:0) after 1m0s
[02:25:22.866] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:13:0)
[02:25:22.866] Timer tick, asking Buildbucket for the build status
[02:25:22.973] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:22.973] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:14:0) after 1m0s
[02:26:23.091] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:14:0)
[02:26:23.091] Timer tick, asking Buildbucket for the build status
[02:26:23.327] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:23.327] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:15:0) after 1m0s
[02:27:23.321] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:15:0)
[02:27:23.321] Timer tick, asking Buildbucket for the build status
[02:27:23.999] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:23.999] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:16:0) after 1m0s
[02:28:24.013] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:16:0)
[02:28:24.013] Timer tick, asking Buildbucket for the build status
[02:28:24.128] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:24.128] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:17:0) after 1m0s
[02:29:24.265] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:17:0)
[02:29:24.265] Timer tick, asking Buildbucket for the build status
[02:29:24.474] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:24.474] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:18:0) after 1m0s
[02:30:24.497] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:18:0)
[02:30:24.497] Timer tick, asking Buildbucket for the build status
[02:30:24.581] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:24.581] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:19:0) after 1m0s
[02:31:24.629] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:19:0)
[02:31:24.629] Timer tick, asking Buildbucket for the build status
[02:31:24.704] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:24.704] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:20:0) after 1m0s
[02:32:24.760] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:20:0)
[02:32:24.760] Timer tick, asking Buildbucket for the build status
[02:32:24.978] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:24.978] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:21:0) after 1m0s
[02:33:25.195] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:21:0)
[02:33:25.195] Timer tick, asking Buildbucket for the build status
[02:33:25.267] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:25.267] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:22:0) after 1m0s
[02:34:25.371] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:22:0)
[02:34:25.371] Timer tick, asking Buildbucket for the build status
[02:34:25.530] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:25.530] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:23:0) after 1m0s
[02:35:25.652] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:23:0)
[02:35:25.652] Timer tick, asking Buildbucket for the build status
[02:35:25.877] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:25.877] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:24:0) after 1m0s
[02:36:25.858] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:24:0)
[02:36:25.858] Timer tick, asking Buildbucket for the build status
[02:36:25.939] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:25.939] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:25:0) after 1m0s
[02:37:26.059] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:25:0)
[02:37:26.059] Timer tick, asking Buildbucket for the build status
[02:37:26.441] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:26.441] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:26:0) after 1m0s
[02:38:26.481] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:26:0)
[02:38:26.481] Timer tick, asking Buildbucket for the build status
[02:38:26.544] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:26.544] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:27:0) after 1m0s
[02:39:26.572] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:27:0)
[02:39:26.572] Timer tick, asking Buildbucket for the build status
[02:39:26.885] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:26.885] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:28:0) after 1m0s
[02:40:26.931] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:28:0)
[02:40:26.931] Timer tick, asking Buildbucket for the build status
[02:40:27.000] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:27.000] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:29:0) after 1m0s
[02:41:27.027] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:29:0)
[02:41:27.027] Timer tick, asking Buildbucket for the build status
[02:41:27.127] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:27.127] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:30:0) after 1m0s
[02:42:27.162] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:30:0)
[02:42:27.162] Timer tick, asking Buildbucket for the build status
[02:42:27.271] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:27.271] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:31:0) after 1m0s
[02:43:27.356] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:31:0)
[02:43:27.356] Timer tick, asking Buildbucket for the build status
[02:43:27.414] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:27.414] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:32:0) after 1m0s
[02:44:27.582] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:32:0)
[02:44:27.582] Timer tick, asking Buildbucket for the build status
[02:44:27.697] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:27.697] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:33:0) after 1m0s
[02:45:27.671] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:33:0)
[02:45:27.671] Timer tick, asking Buildbucket for the build status
[02:45:27.966] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:27.966] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:34:0) after 1m0s
[02:46:28.049] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:34:0)
[02:46:28.049] Timer tick, asking Buildbucket for the build status
[02:46:28.172] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:28.172] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:35:0) after 1m0s
[02:47:28.223] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:35:0)
[02:47:28.223] Timer tick, asking Buildbucket for the build status
[02:47:28.444] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:28.444] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:36:0) after 1m0s
[02:48:28.506] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:36:0)
[02:48:28.506] Timer tick, asking Buildbucket for the build status
[02:48:28.563] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:28.563] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:37:0) after 1m0s
[02:49:28.550] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:37:0)
[02:49:28.550] Timer tick, asking Buildbucket for the build status
[02:49:28.701] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:28.701] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:38:0) after 1m0s
[02:50:28.775] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:38:0)
[02:50:28.775] Timer tick, asking Buildbucket for the build status
[02:50:28.852] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:28.852] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:39:0) after 1m0s
[02:51:28.979] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:39:0)
[02:51:28.980] Timer tick, asking Buildbucket for the build status
[02:51:29.082] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:29.082] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:40:0) after 1m0s
[02:52:29.188] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:40:0)
[02:52:29.188] Timer tick, asking Buildbucket for the build status
[02:52:29.366] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:29.366] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:41:0) after 1m0s
[02:53:29.455] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:41:0)
[02:53:29.455] Timer tick, asking Buildbucket for the build status
[02:53:29.541] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:29.541] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:42:0) after 1m0s
[02:54:29.706] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:42:0)
[02:54:29.706] Timer tick, asking Buildbucket for the build status
[02:54:29.919] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:29.919] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:43:0) after 1m0s
[02:55:29.962] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:43:0)
[02:55:29.962] Timer tick, asking Buildbucket for the build status
[02:55:30.048] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:30.048] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:44:0) after 1m0s
[02:56:30.136] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:44:0)
[02:56:30.136] Timer tick, asking Buildbucket for the build status
[02:56:30.386] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:30.386] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:45:0) after 1m0s
[02:57:30.365] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:45:0)
[02:57:30.365] Timer tick, asking Buildbucket for the build status
[02:57:30.443] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:30.443] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:46:0) after 1m0s
[02:58:30.423] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:46:0)
[02:58:30.423] Timer tick, asking Buildbucket for the build status
[02:58:30.498] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:30.498] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:47:0) after 1m0s
[02:59:30.536] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:47:0)
[02:59:30.536] Timer tick, asking Buildbucket for the build status
[02:59:30.802] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:30.802] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:48:0) after 1m0s
[03:00:30.871] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:48:0)
[03:00:30.871] Timer tick, asking Buildbucket for the build status
[03:00:31.222] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:31.222] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:49:0) after 1m0s
[03:01:31.308] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:49:0)
[03:01:31.308] Timer tick, asking Buildbucket for the build status
[03:01:31.386] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:31.386] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:50:0) after 1m0s
[03:02:31.482] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:50:0)
[03:02:31.482] Timer tick, asking Buildbucket for the build status
[03:02:31.543] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:31.543] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:51:0) after 1m0s
[03:03:31.562] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:51:0)
[03:03:31.562] Timer tick, asking Buildbucket for the build status
[03:03:32.281] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:32.281] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:52:0) after 1m0s
[03:04:32.320] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:52:0)
[03:04:32.320] Timer tick, asking Buildbucket for the build status
[03:04:32.445] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:32.445] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:53:0) after 1m0s
[03:05:32.515] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:53:0)
[03:05:32.515] Timer tick, asking Buildbucket for the build status
[03:05:32.659] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:32.659] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:54:0) after 1m0s
[03:06:32.705] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:54:0)
[03:06:32.705] Timer tick, asking Buildbucket for the build status
[03:06:32.787] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:32.787] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:55:0) after 1m0s
[03:07:32.843] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:55:0)
[03:07:32.843] Timer tick, asking Buildbucket for the build status
[03:07:32.927] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:32.927] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:56:0) after 1m0s
[03:08:32.927] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:56:0)
[03:08:32.927] Timer tick, asking Buildbucket for the build status
[03:08:32.984] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:32.984] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:57:0) after 1m0s
[03:09:33.012] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:57:0)
[03:09:33.012] Timer tick, asking Buildbucket for the build status
[03:09:33.165] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:33.165] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:58:0) after 1m0s
[03:10:33.158] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:58:0)
[03:10:33.158] Timer tick, asking Buildbucket for the build status
[03:10:33.343] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:33.343] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:59:0) after 1m0s
[03:11:33.369] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:59:0)
[03:11:33.369] Timer tick, asking Buildbucket for the build status
[03:11:33.691] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:33.691] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:60:0) after 1m0s
[03:12:33.697] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:60:0)
[03:12:33.697] Timer tick, asking Buildbucket for the build status
[03:12:34.177] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:34.177] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:61:0) after 1m0s
[03:13:34.199] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:61:0)
[03:13:34.199] Timer tick, asking Buildbucket for the build status
[03:13:34.646] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:34.646] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:62:0) after 1m0s
[03:14:34.700] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:62:0)
[03:14:34.700] Timer tick, asking Buildbucket for the build status
[03:14:34.851] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:34.851] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:63:0) after 1m0s
[03:15:34.852] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:63:0)
[03:15:34.852] Timer tick, asking Buildbucket for the build status
[03:15:35.001] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:35.001] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:64:0) after 1m0s
[03:16:35.032] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:64:0)
[03:16:35.032] Timer tick, asking Buildbucket for the build status
[03:16:35.632] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:35.632] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:65:0) after 1m0s
[03:17:35.655] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:65:0)
[03:17:35.655] Timer tick, asking Buildbucket for the build status
[03:17:35.858] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:35.858] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:66:0) after 1m0s
[03:18:35.917] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:66:0)
[03:18:35.917] Timer tick, asking Buildbucket for the build status
[03:18:36.107] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:36.107] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:67:0) after 1m0s
[03:19:36.111] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:67:0)
[03:19:36.111] Timer tick, asking Buildbucket for the build status
[03:19:36.633] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:36.633] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:68:0) after 1m0s
[03:20:36.649] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:68:0)
[03:20:36.649] Timer tick, asking Buildbucket for the build status
[03:20:36.761] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:36.761] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:69:0) after 1m0s
[03:21:36.828] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:69:0)
[03:21:36.828] Timer tick, asking Buildbucket for the build status
[03:21:36.990] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:36.990] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:70:0) after 1m0s
[03:22:36.986] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:70:0)
[03:22:36.986] Timer tick, asking Buildbucket for the build status
[03:22:37.047] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:37.047] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:71:0) after 1m0s
[03:23:37.089] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:71:0)
[03:23:37.090] Timer tick, asking Buildbucket for the build status
[03:23:37.186] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:37.186] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:72:0) after 1m0s
[03:24:37.173] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:72:0)
[03:24:37.173] Timer tick, asking Buildbucket for the build status
[03:24:37.362] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:37.362] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:73:0) after 1m0s
[03:25:37.390] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:73:0)
[03:25:37.390] Timer tick, asking Buildbucket for the build status
[03:25:37.465] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:37.465] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:74:0) after 1m0s
[03:26:37.488] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:74:0)
[03:26:37.488] Timer tick, asking Buildbucket for the build status
[03:26:37.756] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:37.756] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:75:0) after 1m0s
[03:27:37.782] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:75:0)
[03:27:37.782] Timer tick, asking Buildbucket for the build status
[03:27:37.978] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:37.978] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:76:0) after 1m0s
[03:28:38.037] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:76:0)
[03:28:38.037] Timer tick, asking Buildbucket for the build status
[03:28:38.187] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:38.187] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:77:0) after 1m0s
[03:29:38.259] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:77:0)
[03:29:38.259] Timer tick, asking Buildbucket for the build status
[03:29:38.668] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:38.668] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:78:0) after 1m0s
[03:30:38.703] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:78:0)
[03:30:38.703] Timer tick, asking Buildbucket for the build status
[03:30:38.974] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:38.974] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:79:0) after 1m0s
[03:31:39.003] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:79:0)
[03:31:39.003] Timer tick, asking Buildbucket for the build status
[03:31:39.243] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:39.243] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:80:0) after 1m0s
[03:32:39.324] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:80:0)
[03:32:39.324] Timer tick, asking Buildbucket for the build status
[03:32:39.435] Build 8858891991842683776: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:39.435] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9024322053974059008:81:0) after 1m0s
[03:33:28.203] Received PubSub notification, asking Buildbucket for the build status
[03:33:28.282] Build 8858891991842683776: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:33:28.282] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1609904007035474",
"created_by": "project:chromium",
"created_ts": "1609899257770515",
"id": "8858891991842683776",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dd3c4e3981ce672c863bc6a6f60c6658dbcb2188\"}}",
"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\": \"dd3c4e3981ce672c863bc6a6f60c6658dbcb2188\", \"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-30-uvsf\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.fyi\", \"buildername\": \"Linux Viz\", \"buildnumber\": 25735, \"got_angle_revision\": \"e91097bf3a24895f23b928ed12087dcbb1d54d7b\", \"got_dawn_revision\": \"200941c797512895039e9f0315fc18fd9d9734ad\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"dd3c4e3981ce672c863bc6a6f60c6658dbcb2188\", \"got_revision_cp\": \"refs/heads/master@{#840444}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"b9e179f1f239bd1da8fffbd997a41095345d1f6a\", \"got_v8_revision\": \"c8d1d1cbceae8f59975b2ec7d86981ec3fabb11b\", \"got_v8_revision_cp\": \"refs/heads/8.9.212@{#1}\", \"got_webrtc_revision\": \"fc6b87192db6d5e4fd6997814f00b6792cf16180\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32904}\", \"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\": \"dd3c4e3981ce672c863bc6a6f60c6658dbcb2188\", \"swarm_hashes_refs/heads/master(at){#840444}_without_patch\": {\"blink_web_tests\": \"0001ffed37879c61da050525407906ab8dbf07b3\", \"browser_tests\": \"8d5b89416a933dd31f209cd41b7385b2d0908420\", \"content_browsertests\": \"a56693d22a5be8b98d1c9060f1b5efc2b021872d\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_5e40e9df3c25d6ec04b042625e64587de25b7cc9257c9b922c4342550ccccbb8_v2\", \"builder_7701d7ffc8ab5fe38a709c77d4f18478e80f1a1d01dd8053f239f119334d81d1_v2\", \"builder_8c23a72064650b4b1d9380637a287eed4ae2e2a2092116afea022d28ee09b5dd_v2\", \"builder_a7b9f8880f1f76f2cce14c79e9b35da9a713af6654c6d1393cfbcc356d1ede5d_v2\", \"builder_f950d00180feebb913a151f4148df44a669def32bdb4d2b9a63ead64ebacc911_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-30-uvsf\"], \"image\": [\"chrome-xenial-20122700-a13233ce10d\"], \"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-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1609899259199162",
"status": "COMPLETED",
"status_changed_ts": "1609904007367903",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/25735",
"builder:Linux Viz",
"buildset:commit/git/dd3c4e3981ce672c863bc6a6f60c6658dbcb2188",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dd3c4e3981ce672c863bc6a6f60c6658dbcb2188",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024322053974059008",
"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/8858891991842683776/+/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:50ee4abc155e8b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1609904007382230",
"url": "https://ci.chromium.org/b/8858891991842683776",
"utcnow_ts": "1609904008270769"
}
}
[03:33:28.282] Invocation finished in 1h19m11.928530775s with status SUCCEEDED