[21:30:40.726] New invocation is queued and will start shortly
[21:30:42.058] Starting the invocation (attempt 1)
[21:30:42.095] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:30:42.095] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:30:42.095] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9017907511554900928",
"parameters_json": "{\"builder_name\":\"Linux Viz\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"c2d7e012205686090497f47486bcb14be2a266e5\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Viz",
"scheduler_invocation_id:9017907511554900928",
"scheduler_job_id:chromium/Linux Viz",
"user_agent:luci-scheduler",
"buildset:commit/git/c2d7e012205686090497f47486bcb14be2a266e5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c2d7e012205686090497f47486bcb14be2a266e5",
"gitiles_ref:refs/heads/master"
]
}
[21:30:43.145] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1616016642237190",
"id": "8852477449308314064",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c2d7e012205686090497f47486bcb14be2a266e5\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1616016642845039",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/26839",
"builder:Linux Viz",
"buildset:commit/git/c2d7e012205686090497f47486bcb14be2a266e5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c2d7e012205686090497f47486bcb14be2a266e5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017907511554900928",
"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/8852477449308314064/+/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": "1616016642845228",
"url": "https://ci.chromium.org/b/8852477449308314064",
"utcnow_ts": "1616016643129443"
}
}
[21:30:43.145] Task URL: https://ci.chromium.org/b/8852477449308314064
[21:30:43.145] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:2:0) after 1m0s
[21:31:43.121] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:2:0)
[21:31:43.121] Timer tick, asking Buildbucket for the build status
[21:31:43.279] Build 8852477449308314064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:31:43.279] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:3:0) after 1m0s
[21:32:43.298] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:3:0)
[21:32:43.298] Timer tick, asking Buildbucket for the build status
[21:32:43.402] Build 8852477449308314064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:32:43.402] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:4:0) after 1m0s
[21:33:43.442] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:4:0)
[21:33:43.442] Timer tick, asking Buildbucket for the build status
[21:33:43.502] Build 8852477449308314064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:33:43.502] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:5:0) after 1m0s
[21:34:43.515] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:5:0)
[21:34:43.515] Timer tick, asking Buildbucket for the build status
[21:34:43.611] Build 8852477449308314064: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:34:43.611] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:6:0) after 1m0s
[21:35:09.279] Received PubSub notification, asking Buildbucket for the build status
[21:35:09.380] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:43.640] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:6:0)
[21:35:43.640] Timer tick, asking Buildbucket for the build status
[21:35:43.693] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:43.693] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:8:0) after 1m0s
[21:36:43.775] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:8:0)
[21:36:43.775] Timer tick, asking Buildbucket for the build status
[21:36:43.877] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:43.877] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:9:0) after 1m0s
[21:37:43.899] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:9:0)
[21:37:43.899] Timer tick, asking Buildbucket for the build status
[21:37:45.405] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:45.405] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:10:0) after 1m0s
[21:38:45.511] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:10:0)
[21:38:45.511] Timer tick, asking Buildbucket for the build status
[21:38:45.622] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:45.622] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:11:0) after 1m0s
[21:39:45.646] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:11:0)
[21:39:45.646] Timer tick, asking Buildbucket for the build status
[21:39:45.709] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:45.709] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:12:0) after 1m0s
[21:40:45.728] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:12:0)
[21:40:45.728] Timer tick, asking Buildbucket for the build status
[21:40:45.883] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:45.883] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:13:0) after 1m0s
[21:41:45.953] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:13:0)
[21:41:45.953] Timer tick, asking Buildbucket for the build status
[21:41:46.034] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:46.034] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:14:0) after 1m0s
[21:42:46.052] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:14:0)
[21:42:46.052] Timer tick, asking Buildbucket for the build status
[21:42:46.115] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:46.115] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:15:0) after 1m0s
[21:43:46.198] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:15:0)
[21:43:46.198] Timer tick, asking Buildbucket for the build status
[21:43:46.289] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:46.289] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:16:0) after 1m0s
[21:44:46.306] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:16:0)
[21:44:46.306] Timer tick, asking Buildbucket for the build status
[21:44:46.590] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:46.590] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:17:0) after 1m0s
[21:45:46.658] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:17:0)
[21:45:46.658] Timer tick, asking Buildbucket for the build status
[21:45:46.763] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:46.763] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:18:0) after 1m0s
[21:46:46.780] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:18:0)
[21:46:46.780] Timer tick, asking Buildbucket for the build status
[21:46:46.945] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:46.945] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:19:0) after 1m0s
[21:47:46.971] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:19:0)
[21:47:46.971] Timer tick, asking Buildbucket for the build status
[21:47:47.049] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:47.049] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:20:0) after 1m0s
[21:48:47.061] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:20:0)
[21:48:47.061] Timer tick, asking Buildbucket for the build status
[21:48:47.170] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:47.170] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:21:0) after 1m0s
[21:49:47.293] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:21:0)
[21:49:47.293] Timer tick, asking Buildbucket for the build status
[21:49:47.375] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:47.375] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:22:0) after 1m0s
[21:50:47.394] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:22:0)
[21:50:47.394] Timer tick, asking Buildbucket for the build status
[21:50:47.496] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:47.496] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:23:0) after 1m0s
[21:51:47.520] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:23:0)
[21:51:47.593] Build status: STARTED
[21:51:47.593] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:24:0) after 1m16s
[21:53:03.650] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:24:0)
[21:53:03.650] Timer tick, asking Buildbucket for the build status
[21:53:03.899] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:03.899] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:25:0) after 1m0s
[21:54:03.921] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:25:0)
[21:54:03.921] Timer tick, asking Buildbucket for the build status
[21:54:04.088] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:04.088] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:26:0) after 1m0s
[21:55:04.155] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:26:0)
[21:55:04.155] Timer tick, asking Buildbucket for the build status
[21:55:04.377] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:04.377] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:27:0) after 1m0s
[21:56:04.413] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:27:0)
[21:56:04.413] Timer tick, asking Buildbucket for the build status
[21:56:04.491] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:04.491] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:28:0) after 1m0s
[21:57:04.510] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:28:0)
[21:57:04.510] Timer tick, asking Buildbucket for the build status
[21:57:04.592] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:04.592] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:29:0) after 1m0s
[21:58:04.633] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:29:0)
[21:58:04.671] Build status: STARTED
[21:58:04.671] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:30:0) after 2m40s
[22:00:44.692] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:30:0)
[22:00:44.692] Timer tick, asking Buildbucket for the build status
[22:00:44.821] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:44.821] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:31:0) after 1m0s
[22:01:44.898] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:31:0)
[22:01:44.898] Timer tick, asking Buildbucket for the build status
[22:01:44.996] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:44.996] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:32:0) after 1m0s
[22:02:45.079] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:32:0)
[22:02:45.110] Build status: STARTED
[22:02:45.110] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:33:0) after 3m18s
[22:06:03.147] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:33:0)
[22:06:03.147] Timer tick, asking Buildbucket for the build status
[22:06:03.273] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:03.273] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:34:0) after 1m0s
[22:07:03.300] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:34:0)
[22:07:03.300] Timer tick, asking Buildbucket for the build status
[22:07:03.513] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:03.513] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:35:0) after 1m0s
[22:08:03.538] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:35:0)
[22:08:03.538] Timer tick, asking Buildbucket for the build status
[22:08:03.618] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:03.618] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:36:0) after 1m0s
[22:09:03.668] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:36:0)
[22:09:03.668] Timer tick, asking Buildbucket for the build status
[22:09:03.852] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:03.852] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:37:0) after 1m0s
[22:10:03.974] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:37:0)
[22:10:03.974] Timer tick, asking Buildbucket for the build status
[22:10:04.062] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:04.062] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:38:0) after 1m0s
[22:11:04.116] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:38:0)
[22:11:04.116] Timer tick, asking Buildbucket for the build status
[22:11:04.634] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:04.634] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:39:0) after 1m0s
[22:12:04.691] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:39:0)
[22:12:04.691] Timer tick, asking Buildbucket for the build status
[22:12:04.894] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:04.894] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:40:0) after 1m0s
[22:13:04.871] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:40:0)
[22:13:04.871] Timer tick, asking Buildbucket for the build status
[22:13:04.979] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:04.979] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:41:0) after 1m0s
[22:14:05.100] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:41:0)
[22:14:05.100] Timer tick, asking Buildbucket for the build status
[22:14:05.200] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:05.200] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:42:0) after 1m0s
[22:15:05.268] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:42:0)
[22:15:05.268] Timer tick, asking Buildbucket for the build status
[22:15:05.342] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:05.342] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:43:0) after 1m0s
[22:16:05.429] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:43:0)
[22:16:05.457] Build status: STARTED
[22:16:05.457] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:44:0) after 3m24s
[22:19:29.518] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:44:0)
[22:19:29.518] Timer tick, asking Buildbucket for the build status
[22:19:29.601] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:29.601] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:45:0) after 1m0s
[22:20:29.601] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:45:0)
[22:20:29.601] Timer tick, asking Buildbucket for the build status
[22:20:29.746] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:29.746] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:46:0) after 1m0s
[22:21:29.802] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:46:0)
[22:21:29.802] Timer tick, asking Buildbucket for the build status
[22:21:29.896] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:29.896] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:47:0) after 1m0s
[22:22:29.919] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:47:0)
[22:22:29.919] Timer tick, asking Buildbucket for the build status
[22:22:29.986] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:29.986] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:48:0) after 1m0s
[22:23:30.057] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:48:0)
[22:23:30.057] Timer tick, asking Buildbucket for the build status
[22:23:30.352] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:30.352] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:49:0) after 1m0s
[22:24:30.375] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:49:0)
[22:24:30.375] Timer tick, asking Buildbucket for the build status
[22:24:30.462] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:30.462] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:50:0) after 1m0s
[22:25:30.482] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:50:0)
[22:25:30.482] Timer tick, asking Buildbucket for the build status
[22:25:31.825] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:31.825] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:51:0) after 1m0s
[22:26:31.928] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:51:0)
[22:26:31.928] Timer tick, asking Buildbucket for the build status
[22:26:32.018] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:32.018] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:52:0) after 1m0s
[22:27:32.112] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:52:0)
[22:27:32.112] Timer tick, asking Buildbucket for the build status
[22:27:32.390] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:32.390] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:53:0) after 1m0s
[22:28:32.495] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:53:0)
[22:28:32.495] Timer tick, asking Buildbucket for the build status
[22:28:32.681] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:32.681] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:54:0) after 1m0s
[22:29:32.838] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:54:0)
[22:29:32.904] Build status: STARTED
[22:29:32.904] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:55:0) after 8m45s
[22:38:17.979] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:55:0)
[22:38:17.979] Timer tick, asking Buildbucket for the build status
[22:38:18.110] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:18.110] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:56:0) after 1m0s
[22:39:18.316] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:56:0)
[22:39:18.316] Timer tick, asking Buildbucket for the build status
[22:39:18.497] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:18.497] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:57:0) after 1m0s
[22:40:18.523] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:57:0)
[22:40:18.523] Timer tick, asking Buildbucket for the build status
[22:40:18.692] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:18.692] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:58:0) after 1m0s
[22:41:18.823] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:58:0)
[22:41:18.865] Build status: STARTED
[22:41:18.865] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:59:0) after 8m48s
[22:50:06.889] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:59:0)
[22:50:06.918] Build status: STARTED
[22:50:06.918] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:60:0) after 8m35s
[22:58:41.990] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:60:0)
[22:58:42.019] Build status: STARTED
[22:58:42.019] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:61:0) after 1m54s
[23:00:36.072] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:61:0)
[23:00:36.072] Timer tick, asking Buildbucket for the build status
[23:00:36.195] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:36.195] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:62:0) after 1m0s
[23:01:36.251] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:62:0)
[23:01:36.251] Timer tick, asking Buildbucket for the build status
[23:01:36.348] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:36.348] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:63:0) after 1m0s
[23:02:36.415] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:63:0)
[23:02:36.415] Timer tick, asking Buildbucket for the build status
[23:02:36.498] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:36.498] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:64:0) after 1m0s
[23:03:36.543] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:64:0)
[23:03:36.543] Timer tick, asking Buildbucket for the build status
[23:03:36.615] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:36.615] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:65:0) after 1m0s
[23:04:36.594] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:65:0)
[23:04:36.594] Timer tick, asking Buildbucket for the build status
[23:04:36.746] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:36.746] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:66:0) after 1m0s
[23:05:36.783] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:66:0)
[23:05:36.825] Build status: STARTED
[23:05:36.825] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:67:0) after 1m30s
[23:07:06.881] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:67:0)
[23:07:06.881] Timer tick, asking Buildbucket for the build status
[23:07:06.975] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:06.975] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:68:0) after 1m0s
[23:08:07.045] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:68:0)
[23:08:07.101] Build status: STARTED
[23:08:07.101] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:69:0) after 9m45s
[23:17:52.166] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:69:0)
[23:17:52.166] Timer tick, asking Buildbucket for the build status
[23:17:52.414] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:52.414] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:70:0) after 1m0s
[23:18:52.454] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:70:0)
[23:18:52.454] Timer tick, asking Buildbucket for the build status
[23:18:52.526] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:52.526] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:71:0) after 1m0s
[23:19:52.529] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:71:0)
[23:19:52.529] Timer tick, asking Buildbucket for the build status
[23:19:52.600] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:52.600] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:72:0) after 1m0s
[23:20:52.625] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:72:0)
[23:20:52.658] Build status: STARTED
[23:20:52.658] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:73:0) after 1m56s
[23:22:48.677] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:73:0)
[23:22:48.677] Timer tick, asking Buildbucket for the build status
[23:22:48.893] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:48.893] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:74:0) after 1m0s
[23:23:48.920] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:74:0)
[23:23:48.920] Timer tick, asking Buildbucket for the build status
[23:23:49.000] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:49.000] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:75:0) after 1m0s
[23:24:49.054] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:75:0)
[23:24:49.054] Timer tick, asking Buildbucket for the build status
[23:24:49.143] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:49.143] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:76:0) after 1m0s
[23:25:49.183] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:76:0)
[23:25:49.183] Timer tick, asking Buildbucket for the build status
[23:25:49.280] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:49.280] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:77:0) after 1m0s
[23:26:49.299] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:77:0)
[23:26:49.299] Timer tick, asking Buildbucket for the build status
[23:26:49.371] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:49.371] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:78:0) after 1m0s
[23:27:49.441] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:78:0)
[23:27:49.468] Build status: STARTED
[23:27:49.468] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:79:0) after 1m5s
[23:28:54.519] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:79:0)
[23:28:54.519] Timer tick, asking Buildbucket for the build status
[23:28:54.660] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:54.661] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:80:0) after 1m0s
[23:29:54.714] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:80:0)
[23:29:54.739] Build status: STARTED
[23:29:54.739] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:81:0) after 5m39s
[23:35:33.806] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:81:0)
[23:35:33.806] Timer tick, asking Buildbucket for the build status
[23:35:33.905] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:33.905] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:82:0) after 1m0s
[23:36:33.953] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:82:0)
[23:36:33.953] Timer tick, asking Buildbucket for the build status
[23:36:34.183] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:34.183] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:83:0) after 1m0s
[23:37:34.254] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:83:0)
[23:37:34.284] Build status: STARTED
[23:37:34.284] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:84:0) after 3m2s
[23:40:36.306] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:84:0)
[23:40:36.359] Build status: STARTED
[23:40:36.359] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:85:0) after 7m56s
[23:48:32.428] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:85:0)
[23:48:32.428] Timer tick, asking Buildbucket for the build status
[23:48:32.550] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:32.550] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:86:0) after 1m0s
[23:49:32.585] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:86:0)
[23:49:32.585] Timer tick, asking Buildbucket for the build status
[23:49:32.713] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:32.713] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:87:0) after 1m0s
[23:50:32.823] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:87:0)
[23:50:32.852] Build status: STARTED
[23:50:32.852] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:88:0) after 2m33s
[23:53:05.873] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:88:0)
[23:53:05.905] Build status: STARTED
[23:53:05.905] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:89:0) after 1m56s
[23:55:01.948] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:89:0)
[23:55:01.948] Timer tick, asking Buildbucket for the build status
[23:55:02.049] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:02.049] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:90:0) after 1m0s
[23:56:02.035] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:90:0)
[23:56:02.035] Timer tick, asking Buildbucket for the build status
[23:56:02.135] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:02.135] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:91:0) after 1m0s
[23:57:02.284] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:91:0)
[23:57:02.322] Build status: STARTED
[23:57:02.322] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:92:0) after 4m3s
[00:01:05.793] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:92:0)
[00:01:05.793] Timer tick, asking Buildbucket for the build status
[00:01:06.091] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:06.091] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:93:0) after 1m0s
[00:02:06.076] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:93:0)
[00:02:06.076] Timer tick, asking Buildbucket for the build status
[00:02:06.162] Build 8852477449308314064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:06.162] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:94:0) after 1m0s
[00:03:06.197] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:94:0)
[00:03:06.227] Build status: STARTED
[00:03:06.228] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9017907511554900928:95:0) after 8m43s
[00:03:44.181] Received PubSub notification, asking Buildbucket for the build status
[00:03:44.211] Build:
{
"id": "8852477449308314064",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux Viz"
},
"number": 26839,
"createdBy": "project:chromium",
"createTime": "2021-03-17T21:30:42.237190Z",
"startTime": "2021-03-17T21:35:07.483687Z",
"endTime": "2021-03-18T00:03:43.405565Z",
"updateTime": "2021-03-18T00:03:43.717520Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c2d7e012205686090497f47486bcb14be2a266e5",
"ref": "refs/heads/master"
}
}
}
[00:03:44.211] Invocation finished in 2h33m3.500673713s with status SUCCEEDED