[11:50:42.676] New invocation is queued and will start shortly
[11:50:43.843] Starting the invocation (attempt 1)
[11:50:43.890] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:50:43.890] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:50:43.890] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9035248020538041952",
"parameters_json": "{\"builder_name\":\"Linux Viz\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0b4cdcb2cdb8761100e43949951163a10008327f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Viz",
"scheduler_invocation_id:9035248020538041952",
"scheduler_job_id:chromium/Linux Viz",
"user_agent:luci-scheduler",
"buildset:commit/git/0b4cdcb2cdb8761100e43949951163a10008327f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0b4cdcb2cdb8761100e43949951163a10008327f",
"gitiles_ref:refs/heads/master"
]
}
[11:50:44.845] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1599479444075790",
"id": "8869817958407536256",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0b4cdcb2cdb8761100e43949951163a10008327f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1599479444697694",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/23791",
"builder:Linux Viz",
"buildset:commit/git/0b4cdcb2cdb8761100e43949951163a10008327f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0b4cdcb2cdb8761100e43949951163a10008327f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9035248020538041952",
"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/8869817958407536256/+/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": "1599479444697800",
"url": "https://ci.chromium.org/b/8869817958407536256",
"utcnow_ts": "1599479444825601"
}
}
[11:50:44.845] Task URL: https://ci.chromium.org/b/8869817958407536256
[11:50:44.846] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:2:0) after 1m0s
[11:51:04.143] Received PubSub notification, asking Buildbucket for the build status
[11:51:04.311] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:49.950] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:2:0)
[11:51:49.950] Timer tick, asking Buildbucket for the build status
[11:51:50.483] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:50.483] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:4:0) after 1m0s
[11:52:50.777] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:4:0)
[11:52:50.777] Timer tick, asking Buildbucket for the build status
[11:52:51.583] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:51.583] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:5:0) after 1m0s
[11:53:52.048] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:5:0)
[11:53:52.048] Timer tick, asking Buildbucket for the build status
[11:53:52.310] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:52.310] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:6:0) after 1m0s
[11:54:52.351] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:6:0)
[11:54:52.351] Timer tick, asking Buildbucket for the build status
[11:54:53.795] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:53.795] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:7:0) after 1m0s
[11:55:53.854] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:7:0)
[11:55:53.854] Timer tick, asking Buildbucket for the build status
[11:55:54.005] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:54.005] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:8:0) after 1m0s
[11:56:54.402] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:8:0)
[11:56:54.402] Timer tick, asking Buildbucket for the build status
[11:56:54.577] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:54.577] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:9:0) after 1m0s
[11:57:54.758] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:9:0)
[11:57:54.758] Timer tick, asking Buildbucket for the build status
[11:57:54.855] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:54.855] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:10:0) after 1m0s
[11:58:54.902] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:10:0)
[11:58:54.902] Timer tick, asking Buildbucket for the build status
[11:58:55.189] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:55.189] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:11:0) after 1m0s
[11:59:55.195] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:11:0)
[11:59:55.195] Timer tick, asking Buildbucket for the build status
[11:59:55.307] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:55.307] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:12:0) after 1m0s
[12:00:55.464] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:12:0)
[12:00:55.464] Timer tick, asking Buildbucket for the build status
[12:00:55.592] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:55.592] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:13:0) after 1m0s
[12:01:55.922] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:13:0)
[12:01:55.922] Timer tick, asking Buildbucket for the build status
[12:01:56.008] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:56.008] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:14:0) after 1m0s
[12:02:56.704] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:14:0)
[12:02:56.704] Timer tick, asking Buildbucket for the build status
[12:02:56.784] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:56.784] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:15:0) after 1m0s
[12:03:57.363] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:15:0)
[12:03:57.363] Timer tick, asking Buildbucket for the build status
[12:03:57.500] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:57.500] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:16:0) after 1m0s
[12:04:57.646] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:16:0)
[12:04:57.646] Timer tick, asking Buildbucket for the build status
[12:04:57.745] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:57.745] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:17:0) after 1m0s
[12:05:57.939] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:17:0)
[12:05:57.939] Timer tick, asking Buildbucket for the build status
[12:05:58.079] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:58.081] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:18:0) after 1m0s
[12:06:58.352] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:18:0)
[12:06:58.353] Timer tick, asking Buildbucket for the build status
[12:06:58.467] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:58.467] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:19:0) after 1m0s
[12:07:58.583] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:19:0)
[12:07:58.583] Timer tick, asking Buildbucket for the build status
[12:07:58.687] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:58.687] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:20:0) after 1m0s
[12:08:58.713] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:20:0)
[12:08:58.713] Timer tick, asking Buildbucket for the build status
[12:08:58.815] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:58.815] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:21:0) after 1m0s
[12:09:58.937] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:21:0)
[12:09:58.937] Timer tick, asking Buildbucket for the build status
[12:10:00.767] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:00.767] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:22:0) after 1m0s
[12:11:01.062] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:22:0)
[12:11:01.062] Timer tick, asking Buildbucket for the build status
[12:11:03.002] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:03.002] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:23:0) after 1m0s
[12:12:03.285] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:23:0)
[12:12:03.285] Timer tick, asking Buildbucket for the build status
[12:12:03.635] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:03.635] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:24:0) after 1m0s
[12:13:03.715] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:24:0)
[12:13:03.715] Timer tick, asking Buildbucket for the build status
[12:13:03.906] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:03.906] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:25:0) after 1m0s
[12:14:03.930] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:25:0)
[12:14:03.930] Timer tick, asking Buildbucket for the build status
[12:14:04.013] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:04.013] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:26:0) after 1m0s
[12:15:04.036] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:26:0)
[12:15:04.036] Timer tick, asking Buildbucket for the build status
[12:15:04.139] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:04.139] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:27:0) after 1m0s
[12:16:04.177] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:27:0)
[12:16:04.177] Timer tick, asking Buildbucket for the build status
[12:16:04.777] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:04.777] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:28:0) after 1m0s
[12:17:04.977] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:28:0)
[12:17:04.977] Timer tick, asking Buildbucket for the build status
[12:17:05.055] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:05.055] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:29:0) after 1m0s
[12:18:05.281] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:29:0)
[12:18:05.281] Timer tick, asking Buildbucket for the build status
[12:18:05.373] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:05.373] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:30:0) after 1m0s
[12:19:05.540] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:30:0)
[12:19:05.540] Timer tick, asking Buildbucket for the build status
[12:19:05.748] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:05.748] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:31:0) after 1m0s
[12:20:05.907] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:31:0)
[12:20:05.907] Timer tick, asking Buildbucket for the build status
[12:20:06.031] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:06.031] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:32:0) after 1m0s
[12:21:06.309] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:32:0)
[12:21:06.309] Timer tick, asking Buildbucket for the build status
[12:21:06.498] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:06.498] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:33:0) after 1m0s
[12:22:06.709] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:33:0)
[12:22:06.709] Timer tick, asking Buildbucket for the build status
[12:22:06.799] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:06.799] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:34:0) after 1m0s
[12:23:07.003] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:34:0)
[12:23:07.003] Timer tick, asking Buildbucket for the build status
[12:23:07.164] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:07.164] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:35:0) after 1m0s
[12:24:07.316] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:35:0)
[12:24:07.316] Timer tick, asking Buildbucket for the build status
[12:24:07.452] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:07.452] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:36:0) after 1m0s
[12:25:08.223] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:36:0)
[12:25:08.223] Timer tick, asking Buildbucket for the build status
[12:25:08.302] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:08.302] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:37:0) after 1m0s
[12:26:08.391] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:37:0)
[12:26:08.391] Timer tick, asking Buildbucket for the build status
[12:26:08.699] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:08.700] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:38:0) after 1m0s
[12:27:08.811] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:38:0)
[12:27:08.811] Timer tick, asking Buildbucket for the build status
[12:27:09.031] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:09.031] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:39:0) after 1m0s
[12:28:09.107] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:39:0)
[12:28:09.107] Timer tick, asking Buildbucket for the build status
[12:28:09.275] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:09.275] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:40:0) after 1m0s
[12:29:09.355] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:40:0)
[12:29:09.355] Timer tick, asking Buildbucket for the build status
[12:29:09.468] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:09.468] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:41:0) after 1m0s
[12:30:09.516] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:41:0)
[12:30:09.517] Timer tick, asking Buildbucket for the build status
[12:30:09.624] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:09.624] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:42:0) after 1m0s
[12:31:09.660] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:42:0)
[12:31:09.660] Timer tick, asking Buildbucket for the build status
[12:31:09.937] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:09.937] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:43:0) after 1m0s
[12:32:09.998] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:43:0)
[12:32:09.998] Timer tick, asking Buildbucket for the build status
[12:32:10.133] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:10.133] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:44:0) after 1m0s
[12:33:10.170] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:44:0)
[12:33:10.170] Timer tick, asking Buildbucket for the build status
[12:33:10.255] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:10.255] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:45:0) after 1m0s
[12:34:10.291] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:45:0)
[12:34:10.291] Timer tick, asking Buildbucket for the build status
[12:34:10.369] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:10.369] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:46:0) after 1m0s
[12:35:10.422] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:46:0)
[12:35:10.422] Timer tick, asking Buildbucket for the build status
[12:35:10.525] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:10.525] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:47:0) after 1m0s
[12:36:10.615] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:47:0)
[12:36:10.615] Timer tick, asking Buildbucket for the build status
[12:36:10.810] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:10.810] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:48:0) after 1m0s
[12:37:10.878] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:48:0)
[12:37:10.878] Timer tick, asking Buildbucket for the build status
[12:37:11.216] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:11.216] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:49:0) after 1m0s
[12:38:11.290] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:49:0)
[12:38:11.290] Timer tick, asking Buildbucket for the build status
[12:38:11.393] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:11.393] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:50:0) after 1m0s
[12:39:11.477] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:50:0)
[12:39:11.477] Timer tick, asking Buildbucket for the build status
[12:39:11.578] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:11.578] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:51:0) after 1m0s
[12:40:11.800] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:51:0)
[12:40:11.800] Timer tick, asking Buildbucket for the build status
[12:40:11.896] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:11.896] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:52:0) after 1m0s
[12:41:11.966] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:52:0)
[12:41:11.966] Timer tick, asking Buildbucket for the build status
[12:41:12.543] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:12.543] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:53:0) after 1m0s
[12:42:12.664] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:53:0)
[12:42:12.664] Timer tick, asking Buildbucket for the build status
[12:42:12.758] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:12.758] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:54:0) after 1m0s
[12:43:12.932] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:54:0)
[12:43:12.932] Timer tick, asking Buildbucket for the build status
[12:43:13.707] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:13.715] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:55:0) after 1m0s
[12:44:13.885] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:55:0)
[12:44:13.885] Timer tick, asking Buildbucket for the build status
[12:44:14.029] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:14.029] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:56:0) after 1m0s
[12:45:14.471] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:56:0)
[12:45:14.471] Timer tick, asking Buildbucket for the build status
[12:45:14.688] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:14.688] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:57:0) after 1m0s
[12:46:14.829] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:57:0)
[12:46:14.829] Timer tick, asking Buildbucket for the build status
[12:46:14.969] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:14.969] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:58:0) after 1m0s
[12:47:15.359] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:58:0)
[12:47:15.359] Timer tick, asking Buildbucket for the build status
[12:47:15.587] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:15.588] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:59:0) after 1m0s
[12:48:15.643] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:59:0)
[12:48:15.643] Timer tick, asking Buildbucket for the build status
[12:48:15.734] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:15.734] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:60:0) after 1m0s
[12:49:16.310] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:60:0)
[12:49:16.310] Timer tick, asking Buildbucket for the build status
[12:49:16.467] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:16.467] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:61:0) after 1m0s
[12:50:16.545] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:61:0)
[12:50:16.545] Timer tick, asking Buildbucket for the build status
[12:50:16.710] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:16.710] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:62:0) after 1m0s
[12:51:16.840] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:62:0)
[12:51:16.840] Timer tick, asking Buildbucket for the build status
[12:51:16.955] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:16.955] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:63:0) after 1m0s
[12:52:17.062] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:63:0)
[12:52:17.062] Timer tick, asking Buildbucket for the build status
[12:52:17.219] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:17.219] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:64:0) after 1m0s
[12:53:17.300] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:64:0)
[12:53:17.300] Timer tick, asking Buildbucket for the build status
[12:53:17.372] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:17.372] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:65:0) after 1m0s
[12:54:17.511] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:65:0)
[12:54:17.511] Timer tick, asking Buildbucket for the build status
[12:54:17.574] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:17.574] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:66:0) after 1m0s
[12:55:17.681] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:66:0)
[12:55:17.681] Timer tick, asking Buildbucket for the build status
[12:55:17.762] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:17.762] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:67:0) after 1m0s
[12:56:17.882] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:67:0)
[12:56:17.882] Timer tick, asking Buildbucket for the build status
[12:56:18.134] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:18.134] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:68:0) after 1m0s
[12:57:18.149] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:68:0)
[12:57:18.149] Timer tick, asking Buildbucket for the build status
[12:57:18.244] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:18.244] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:69:0) after 1m0s
[12:58:18.464] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:69:0)
[12:58:18.464] Timer tick, asking Buildbucket for the build status
[12:58:18.710] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:18.710] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:70:0) after 1m0s
[12:59:18.741] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:70:0)
[12:59:18.741] Timer tick, asking Buildbucket for the build status
[12:59:19.158] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:19.158] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:71:0) after 1m0s
[13:00:19.136] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:71:0)
[13:00:19.136] Timer tick, asking Buildbucket for the build status
[13:00:19.328] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:19.328] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:72:0) after 1m0s
[13:01:20.514] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:72:0)
[13:01:20.514] Timer tick, asking Buildbucket for the build status
[13:01:20.730] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:20.730] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:73:0) after 1m0s
[13:02:20.777] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:73:0)
[13:02:20.777] Timer tick, asking Buildbucket for the build status
[13:02:20.881] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:20.881] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:74:0) after 1m0s
[13:03:20.922] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:74:0)
[13:03:20.922] Timer tick, asking Buildbucket for the build status
[13:03:21.063] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:21.063] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:75:0) after 1m0s
[13:04:21.202] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:75:0)
[13:04:21.202] Timer tick, asking Buildbucket for the build status
[13:04:21.368] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:21.368] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:76:0) after 1m0s
[13:05:21.468] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:76:0)
[13:05:21.468] Timer tick, asking Buildbucket for the build status
[13:05:21.650] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:21.650] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:77:0) after 1m0s
[13:06:21.763] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:77:0)
[13:06:21.763] Timer tick, asking Buildbucket for the build status
[13:06:22.169] Build 8869817958407536256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:22.169] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9035248020538041952:78:0) after 1m0s
[13:06:41.755] Received PubSub notification, asking Buildbucket for the build status
[13:06:41.946] Build 8869817958407536256: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:06:41.946] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1599483999064809",
"created_by": "project:chromium",
"created_ts": "1599479444075790",
"id": "8869817958407536256",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0b4cdcb2cdb8761100e43949951163a10008327f\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"0b4cdcb2cdb8761100e43949951163a10008327f\", \"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-108-4ala\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.fyi\", \"buildername\": \"Linux Viz\", \"buildnumber\": 23791, \"got_angle_revision\": \"3cdd19d95d7e64446da7a9109b132488e5a609b2\", \"got_dawn_revision\": \"62442aceb0fb8af0c1edd07b5f18b4ec181b8fbf\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"0b4cdcb2cdb8761100e43949951163a10008327f\", \"got_revision_cp\": \"refs/heads/master@{#804715}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"10121443c16deb0435be8ea61be54e3bee1b9855\", \"got_v8_revision\": \"3e2d6551eed76b7bdafb278b44c630965a840da8\", \"got_v8_revision_cp\": \"refs/heads/8.7.75@{#1}\", \"got_webrtc_revision\": \"d46db9f1523ae45909b4a6fdc90a140443068bc6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32036}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0b4cdcb2cdb8761100e43949951163a10008327f\", \"swarm_hashes\": {\"blink_web_tests\": \"f879c29b9bd9ff8f5593ff90964cf6f34fea2616\", \"browser_tests\": \"b63001bbbac524064a8fc4faf28dea42986443c8\", \"content_browsertests\": \"4d25c97fe616fb290dad7c703fef620bb044be1f\"}, \"swarm_hashes_refs/heads/master(at){#804715}_without_patch\": {\"blink_web_tests\": \"f879c29b9bd9ff8f5593ff90964cf6f34fea2616\", \"browser_tests\": \"b63001bbbac524064a8fc4faf28dea42986443c8\", \"content_browsertests\": \"4d25c97fe616fb290dad7c703fef620bb044be1f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_5e40e9df3c25d6ec04b042625e64587de25b7cc9257c9b922c4342550ccccbb8_v2\", \"builder_756d7885c4e2fdf98a0ecf3a0240e3f6188de5be53188a09baa9eae15dedd66f_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-108-4ala\"], \"image\": [\"chrome-xenial-20081700-d9392568d25\"], \"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\": [\"5511-73542fe\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1599479461916036",
"status": "COMPLETED",
"status_changed_ts": "1599483999967191",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/23791",
"builder:Linux Viz",
"buildset:commit/git/0b4cdcb2cdb8761100e43949951163a10008327f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0b4cdcb2cdb8761100e43949951163a10008327f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9035248020538041952",
"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/8869817958407536256/+/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:4e813922aa6e9610",
"user_agent:luci-scheduler"
],
"updated_ts": "1599484000267936",
"url": "https://ci.chromium.org/b/8869817958407536256",
"utcnow_ts": "1599484001928328"
}
}
[13:06:41.946] Invocation finished in 1h15m59.28606726s with status SUCCEEDED