[18:31:51.336] New invocation is queued and will start shortly
[18:31:52.462] Starting the invocation (attempt 1)
[18:31:52.510] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:31:52.511] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:31:52.511] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028880995075120544",
"parameters_json": "{\"builder_name\":\"Linux Viz\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"9437595042e52325477640fca6ebf7e948e0adff\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Viz",
"scheduler_invocation_id:9028880995075120544",
"scheduler_job_id:chromium/Linux Viz",
"user_agent:luci-scheduler",
"buildset:commit/git/9437595042e52325477640fca6ebf7e948e0adff",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9437595042e52325477640fca6ebf7e948e0adff",
"gitiles_ref:refs/heads/master"
]
}
[18:31:53.808] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605551512658299",
"id": "8863450933021686720",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9437595042e52325477640fca6ebf7e948e0adff\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605551513569238",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/24850",
"builder:Linux Viz",
"buildset:commit/git/9437595042e52325477640fca6ebf7e948e0adff",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9437595042e52325477640fca6ebf7e948e0adff",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028880995075120544",
"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/8863450933021686720/+/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": "1605551513570631",
"url": "https://ci.chromium.org/b/8863450933021686720",
"utcnow_ts": "1605551513788407"
}
}
[18:31:53.808] Task URL: https://ci.chromium.org/b/8863450933021686720
[18:31:53.808] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:2:0) after 1m0s
[18:31:57.666] Received PubSub notification, asking Buildbucket for the build status
[18:31:57.767] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:53.955] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:2:0)
[18:32:53.955] Timer tick, asking Buildbucket for the build status
[18:32:54.088] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:54.088] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:4:0) after 1m0s
[18:33:54.267] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:4:0)
[18:33:54.267] Timer tick, asking Buildbucket for the build status
[18:33:54.337] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:54.337] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:5:0) after 1m0s
[18:34:54.477] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:5:0)
[18:34:54.477] Timer tick, asking Buildbucket for the build status
[18:34:54.583] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:54.583] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:6:0) after 1m0s
[18:35:54.642] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:6:0)
[18:35:54.642] Timer tick, asking Buildbucket for the build status
[18:35:54.782] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:54.782] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:7:0) after 1m0s
[18:36:54.847] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:7:0)
[18:36:54.847] Timer tick, asking Buildbucket for the build status
[18:36:54.959] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:54.959] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:8:0) after 1m0s
[18:37:55.064] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:8:0)
[18:37:55.064] Timer tick, asking Buildbucket for the build status
[18:37:55.252] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:55.252] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:9:0) after 1m0s
[18:38:55.311] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:9:0)
[18:38:55.311] Timer tick, asking Buildbucket for the build status
[18:38:55.407] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:55.407] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:10:0) after 1m0s
[18:39:55.576] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:10:0)
[18:39:55.576] Timer tick, asking Buildbucket for the build status
[18:39:55.648] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:55.648] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:11:0) after 1m0s
[18:40:55.701] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:11:0)
[18:40:55.701] Timer tick, asking Buildbucket for the build status
[18:40:55.810] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:55.810] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:12:0) after 1m0s
[18:41:55.838] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:12:0)
[18:41:55.838] Timer tick, asking Buildbucket for the build status
[18:41:56.310] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:56.310] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:13:0) after 1m0s
[18:42:56.402] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:13:0)
[18:42:56.402] Timer tick, asking Buildbucket for the build status
[18:42:56.589] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:56.589] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:14:0) after 1m0s
[18:43:56.678] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:14:0)
[18:43:56.679] Timer tick, asking Buildbucket for the build status
[18:43:56.761] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:56.761] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:15:0) after 1m0s
[18:44:56.784] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:15:0)
[18:44:56.784] Timer tick, asking Buildbucket for the build status
[18:44:56.916] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:56.916] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:16:0) after 1m0s
[18:45:56.970] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:16:0)
[18:45:56.970] Timer tick, asking Buildbucket for the build status
[18:45:57.049] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:57.049] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:17:0) after 1m0s
[18:46:57.082] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:17:0)
[18:46:57.083] Timer tick, asking Buildbucket for the build status
[18:46:57.507] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:57.507] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:18:0) after 1m0s
[18:47:57.569] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:18:0)
[18:47:57.569] Timer tick, asking Buildbucket for the build status
[18:47:57.657] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:57.657] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:19:0) after 1m0s
[18:48:57.716] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:19:0)
[18:48:57.716] Timer tick, asking Buildbucket for the build status
[18:48:58.237] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:58.237] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:20:0) after 1m0s
[18:49:58.374] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:20:0)
[18:49:58.374] Timer tick, asking Buildbucket for the build status
[18:49:58.446] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:58.446] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:21:0) after 1m0s
[18:50:58.503] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:21:0)
[18:50:58.503] Timer tick, asking Buildbucket for the build status
[18:50:58.588] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:58.588] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:22:0) after 1m0s
[18:51:58.642] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:22:0)
[18:51:58.642] Timer tick, asking Buildbucket for the build status
[18:51:58.890] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:58.890] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:23:0) after 1m0s
[18:52:58.977] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:23:0)
[18:52:58.978] Timer tick, asking Buildbucket for the build status
[18:52:59.065] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:59.065] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:24:0) after 1m0s
[18:53:59.123] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:24:0)
[18:53:59.123] Timer tick, asking Buildbucket for the build status
[18:53:59.213] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:59.213] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:25:0) after 1m0s
[18:54:59.322] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:25:0)
[18:54:59.322] Timer tick, asking Buildbucket for the build status
[18:54:59.404] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:59.404] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:26:0) after 1m0s
[18:55:59.464] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:26:0)
[18:55:59.464] Timer tick, asking Buildbucket for the build status
[18:55:59.556] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:59.556] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:27:0) after 1m0s
[18:56:59.639] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:27:0)
[18:56:59.639] Timer tick, asking Buildbucket for the build status
[18:56:59.803] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:59.803] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:28:0) after 1m0s
[18:57:59.844] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:28:0)
[18:57:59.844] Timer tick, asking Buildbucket for the build status
[18:57:59.989] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:59.989] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:29:0) after 1m0s
[18:59:00.097] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:29:0)
[18:59:00.097] Timer tick, asking Buildbucket for the build status
[18:59:01.145] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:01.146] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:30:0) after 1m0s
[19:00:01.159] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:30:0)
[19:00:01.159] Timer tick, asking Buildbucket for the build status
[19:00:01.246] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:01.246] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:31:0) after 1m0s
[19:01:01.394] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:31:0)
[19:01:01.394] Timer tick, asking Buildbucket for the build status
[19:01:01.498] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:01.498] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:32:0) after 1m0s
[19:02:01.551] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:32:0)
[19:02:01.551] Timer tick, asking Buildbucket for the build status
[19:02:01.715] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:01.715] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:33:0) after 1m0s
[19:03:01.856] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:33:0)
[19:03:01.856] Timer tick, asking Buildbucket for the build status
[19:03:01.935] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:01.935] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:34:0) after 1m0s
[19:04:02.057] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:34:0)
[19:04:02.057] Timer tick, asking Buildbucket for the build status
[19:04:02.226] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:02.226] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:35:0) after 1m0s
[19:05:02.314] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:35:0)
[19:05:02.314] Timer tick, asking Buildbucket for the build status
[19:05:03.273] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:03.273] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:36:0) after 1m0s
[19:06:03.322] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:36:0)
[19:06:03.322] Timer tick, asking Buildbucket for the build status
[19:06:04.389] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:04.390] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:37:0) after 1m0s
[19:07:04.468] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:37:0)
[19:07:04.468] Timer tick, asking Buildbucket for the build status
[19:07:05.157] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:05.157] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:38:0) after 1m0s
[19:08:05.228] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:38:0)
[19:08:05.228] Timer tick, asking Buildbucket for the build status
[19:08:05.319] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:05.319] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:39:0) after 1m0s
[19:09:05.429] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:39:0)
[19:09:05.429] Timer tick, asking Buildbucket for the build status
[19:09:05.512] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:05.512] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:40:0) after 1m0s
[19:10:05.567] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:40:0)
[19:10:05.567] Timer tick, asking Buildbucket for the build status
[19:10:05.711] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:05.711] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:41:0) after 1m0s
[19:11:05.940] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:41:0)
[19:11:05.940] Timer tick, asking Buildbucket for the build status
[19:11:06.722] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:06.722] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:42:0) after 1m0s
[19:12:06.789] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:42:0)
[19:12:06.789] Timer tick, asking Buildbucket for the build status
[19:12:06.963] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:06.963] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:43:0) after 1m0s
[19:13:07.018] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:43:0)
[19:13:07.018] Timer tick, asking Buildbucket for the build status
[19:13:07.083] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:07.083] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:44:0) after 1m0s
[19:14:07.134] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:44:0)
[19:14:07.134] Timer tick, asking Buildbucket for the build status
[19:14:07.275] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:07.275] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:45:0) after 1m0s
[19:15:07.259] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:45:0)
[19:15:07.259] Timer tick, asking Buildbucket for the build status
[19:15:07.324] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:07.324] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:46:0) after 1m0s
[19:16:07.446] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:46:0)
[19:16:07.446] Timer tick, asking Buildbucket for the build status
[19:16:07.958] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:07.958] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:47:0) after 1m0s
[19:17:08.132] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:47:0)
[19:17:08.132] Timer tick, asking Buildbucket for the build status
[19:17:08.253] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:08.253] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:48:0) after 1m0s
[19:18:08.298] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:48:0)
[19:18:08.298] Timer tick, asking Buildbucket for the build status
[19:18:08.466] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:08.466] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:49:0) after 1m0s
[19:19:08.512] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:49:0)
[19:19:08.513] Timer tick, asking Buildbucket for the build status
[19:19:08.607] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:08.607] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:50:0) after 1m0s
[19:20:08.727] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:50:0)
[19:20:08.727] Timer tick, asking Buildbucket for the build status
[19:20:08.995] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:08.995] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:51:0) after 1m0s
[19:21:09.064] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:51:0)
[19:21:09.064] Timer tick, asking Buildbucket for the build status
[19:21:09.599] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:09.599] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:52:0) after 1m0s
[19:22:09.656] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:52:0)
[19:22:09.656] Timer tick, asking Buildbucket for the build status
[19:22:09.957] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:09.957] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:53:0) after 1m0s
[19:23:09.987] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:53:0)
[19:23:09.987] Timer tick, asking Buildbucket for the build status
[19:23:10.243] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:10.243] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:54:0) after 1m0s
[19:24:10.413] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:54:0)
[19:24:10.413] Timer tick, asking Buildbucket for the build status
[19:24:10.538] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:10.538] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:55:0) after 1m0s
[19:25:10.703] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:55:0)
[19:25:10.703] Timer tick, asking Buildbucket for the build status
[19:25:10.819] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:10.819] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:56:0) after 1m0s
[19:26:10.831] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:56:0)
[19:26:10.831] Timer tick, asking Buildbucket for the build status
[19:26:11.008] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:11.008] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:57:0) after 1m0s
[19:27:11.068] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:57:0)
[19:27:11.068] Timer tick, asking Buildbucket for the build status
[19:27:11.218] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:11.218] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:58:0) after 1m0s
[19:28:11.243] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:58:0)
[19:28:11.243] Timer tick, asking Buildbucket for the build status
[19:28:11.442] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:11.442] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:59:0) after 1m0s
[19:29:11.524] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:59:0)
[19:29:11.524] Timer tick, asking Buildbucket for the build status
[19:29:11.725] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:11.725] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:60:0) after 1m0s
[19:30:11.881] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:60:0)
[19:30:11.881] Timer tick, asking Buildbucket for the build status
[19:30:12.329] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:12.329] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:61:0) after 1m0s
[19:31:12.491] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:61:0)
[19:31:12.491] Timer tick, asking Buildbucket for the build status
[19:31:12.565] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:12.565] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:62:0) after 1m0s
[19:32:12.619] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:62:0)
[19:32:12.619] Timer tick, asking Buildbucket for the build status
[19:32:12.801] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:12.801] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:63:0) after 1m0s
[19:33:12.891] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:63:0)
[19:33:12.891] Timer tick, asking Buildbucket for the build status
[19:33:12.984] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:12.984] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:64:0) after 1m0s
[19:34:13.016] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:64:0)
[19:34:13.016] Timer tick, asking Buildbucket for the build status
[19:34:13.246] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:13.246] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:65:0) after 1m0s
[19:35:13.378] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:65:0)
[19:35:13.378] Timer tick, asking Buildbucket for the build status
[19:35:13.498] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:13.498] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:66:0) after 1m0s
[19:36:13.538] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:66:0)
[19:36:13.538] Timer tick, asking Buildbucket for the build status
[19:36:13.613] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:13.613] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:67:0) after 1m0s
[19:37:13.631] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:67:0)
[19:37:13.631] Timer tick, asking Buildbucket for the build status
[19:37:13.922] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:13.922] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:68:0) after 1m0s
[19:38:13.963] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:68:0)
[19:38:13.963] Timer tick, asking Buildbucket for the build status
[19:38:14.194] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:14.195] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:69:0) after 1m0s
[19:39:14.289] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:69:0)
[19:39:14.289] Timer tick, asking Buildbucket for the build status
[19:39:14.437] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:14.437] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:70:0) after 1m0s
[19:40:14.475] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:70:0)
[19:40:14.475] Timer tick, asking Buildbucket for the build status
[19:40:14.621] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:14.621] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:71:0) after 1m0s
[19:41:14.646] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:71:0)
[19:41:14.646] Timer tick, asking Buildbucket for the build status
[19:41:14.778] Build 8863450933021686720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:14.778] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:9028880995075120544:72:0) after 1m0s
[19:41:16.975] Received PubSub notification, asking Buildbucket for the build status
[19:41:17.126] Build 8863450933021686720: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:41:17.126] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605555675253166",
"created_by": "project:chromium",
"created_ts": "1605551512658299",
"id": "8863450933021686720",
"parameters_json": "{\"builder_name\": \"Linux Viz\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9437595042e52325477640fca6ebf7e948e0adff\"}}",
"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\": \"9437595042e52325477640fca6ebf7e948e0adff\", \"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-52-88z2\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.fyi\", \"buildername\": \"Linux Viz\", \"buildnumber\": 24850, \"got_angle_revision\": \"f46e6201b40bdee3e0b79d5d97660fc302fd76f5\", \"got_dawn_revision\": \"dd6b36e8801ebeee53342c295fb56812504db80d\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"9437595042e52325477640fca6ebf7e948e0adff\", \"got_revision_cp\": \"refs/heads/master@{#827838}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"8dceaddf8503b9a83e07ff7788b3edfbb0d9665a\", \"got_v8_revision\": \"cfb8bc492eb44482518665f7d1013d3c2e8f5000\", \"got_v8_revision_cp\": \"refs/heads/8.9.1@{#1}\", \"got_webrtc_revision\": \"02736010cb171c3cc36ea2039911a6dadec9952d\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32613}\", \"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\": \"9437595042e52325477640fca6ebf7e948e0adff\", \"swarm_hashes_refs/heads/master(at){#827838}_without_patch\": {\"blink_web_tests\": \"df29d58c3897099aeeb857a0612dbab463aabf1f\", \"browser_tests\": \"39f6805c1cefeec260e06bbc390cd16d066eb04d\", \"content_browsertests\": \"3eede649541ec37b69f29cf179ebeb7cf494cb11\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_5e40e9df3c25d6ec04b042625e64587de25b7cc9257c9b922c4342550ccccbb8_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-52-88z2\"], \"image\": [\"chrome-xenial-20110800-08efb743065\"], \"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\": [\"5632-2e614e1\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1605551516764785",
"status": "COMPLETED",
"status_changed_ts": "1605555676221521",
"tags": [
"build_address:luci.chromium.ci/Linux Viz/24850",
"builder:Linux Viz",
"buildset:commit/git/9437595042e52325477640fca6ebf7e948e0adff",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9437595042e52325477640fca6ebf7e948e0adff",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028880995075120544",
"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/8863450933021686720/+/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:4feb256bea0d6a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1605555676243879",
"url": "https://ci.chromium.org/b/8863450933021686720",
"utcnow_ts": "1605555677112884"
}
}
[19:41:17.127] Invocation finished in 1h9m25.81034198s with status SUCCEEDED