[23:18:48.971] New invocation is queued and will start shortly
[23:18:50.933] Starting the invocation (attempt 1)
[23:18:50.988] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:18:50.990] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:18:50.992] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029859507705791024",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"9a749c24587de396d0e4ecfdd2b7670eef0f78ff\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9029859507705791024",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/9a749c24587de396d0e4ecfdd2b7670eef0f78ff",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9a749c24587de396d0e4ecfdd2b7670eef0f78ff",
"gitiles_ref:refs/heads/master"
]
}
[23:18:52.649] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1604618331106303",
"id": "8864429444800797024",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9a749c24587de396d0e4ecfdd2b7670eef0f78ff\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604618332113157",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42616",
"builder:linux-chromeos-rel",
"buildset:commit/git/9a749c24587de396d0e4ecfdd2b7670eef0f78ff",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9a749c24587de396d0e4ecfdd2b7670eef0f78ff",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029859507705791024",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8864429444800797024/+/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": "1604618332113477",
"url": "https://ci.chromium.org/b/8864429444800797024",
"utcnow_ts": "1604618332588343"
}
}
[23:18:52.649] Task URL: https://ci.chromium.org/b/8864429444800797024
[23:18:52.649] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:2:0) after 1m0s
[23:18:55.212] Received PubSub notification, asking Buildbucket for the build status
[23:18:55.288] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:52.686] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:2:0)
[23:19:52.686] Timer tick, asking Buildbucket for the build status
[23:19:52.804] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:52.804] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:4:0) after 1m0s
[23:20:52.904] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:4:0)
[23:20:52.904] Timer tick, asking Buildbucket for the build status
[23:20:53.183] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:53.183] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:5:0) after 1m0s
[23:21:53.182] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:5:0)
[23:21:53.182] Timer tick, asking Buildbucket for the build status
[23:21:53.311] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:53.311] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:6:0) after 1m0s
[23:22:53.355] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:6:0)
[23:22:53.355] Timer tick, asking Buildbucket for the build status
[23:22:53.478] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:53.478] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:7:0) after 1m0s
[23:23:53.589] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:7:0)
[23:23:53.589] Timer tick, asking Buildbucket for the build status
[23:23:54.121] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:54.121] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:8:0) after 1m0s
[23:24:54.134] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:8:0)
[23:24:54.134] Timer tick, asking Buildbucket for the build status
[23:24:54.290] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:54.290] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:9:0) after 1m0s
[23:25:54.361] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:9:0)
[23:25:54.361] Timer tick, asking Buildbucket for the build status
[23:25:54.444] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:54.444] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:10:0) after 1m0s
[23:26:54.485] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:10:0)
[23:26:54.485] Timer tick, asking Buildbucket for the build status
[23:26:54.549] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:54.549] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:11:0) after 1m0s
[23:27:54.637] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:11:0)
[23:27:54.637] Timer tick, asking Buildbucket for the build status
[23:27:54.716] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:54.716] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:12:0) after 1m0s
[23:28:54.801] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:12:0)
[23:28:54.801] Timer tick, asking Buildbucket for the build status
[23:28:55.243] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:55.243] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:13:0) after 1m0s
[23:29:55.427] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:13:0)
[23:29:55.427] Timer tick, asking Buildbucket for the build status
[23:29:56.464] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:56.465] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:14:0) after 1m0s
[23:30:56.592] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:14:0)
[23:30:56.593] Timer tick, asking Buildbucket for the build status
[23:30:56.668] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:56.668] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:15:0) after 1m0s
[23:31:56.877] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:15:0)
[23:31:56.877] Timer tick, asking Buildbucket for the build status
[23:31:57.018] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:57.018] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:16:0) after 1m0s
[23:32:57.190] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:16:0)
[23:32:57.190] Timer tick, asking Buildbucket for the build status
[23:32:57.284] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:57.284] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:17:0) after 1m0s
[23:33:57.439] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:17:0)
[23:33:57.439] Timer tick, asking Buildbucket for the build status
[23:33:57.650] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:57.650] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:18:0) after 1m0s
[23:34:57.673] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:18:0)
[23:34:57.673] Timer tick, asking Buildbucket for the build status
[23:34:58.692] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:58.692] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:19:0) after 1m0s
[23:35:58.744] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:19:0)
[23:35:58.744] Timer tick, asking Buildbucket for the build status
[23:35:58.954] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:58.954] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:20:0) after 1m0s
[23:36:59.126] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:20:0)
[23:36:59.126] Timer tick, asking Buildbucket for the build status
[23:36:59.202] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:59.202] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:21:0) after 1m0s
[23:37:59.260] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:21:0)
[23:37:59.260] Timer tick, asking Buildbucket for the build status
[23:37:59.509] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:59.509] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:22:0) after 1m0s
[23:38:59.525] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:22:0)
[23:38:59.525] Timer tick, asking Buildbucket for the build status
[23:38:59.755] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:59.755] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:23:0) after 1m0s
[23:39:59.833] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:23:0)
[23:39:59.833] Timer tick, asking Buildbucket for the build status
[23:39:59.904] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:59.904] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:24:0) after 1m0s
[23:40:59.959] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:24:0)
[23:40:59.959] Timer tick, asking Buildbucket for the build status
[23:41:00.172] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:00.172] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:25:0) after 1m0s
[23:42:01.185] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:25:0)
[23:42:01.185] Timer tick, asking Buildbucket for the build status
[23:42:01.443] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:01.443] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:26:0) after 1m0s
[23:43:01.611] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:26:0)
[23:43:01.611] Timer tick, asking Buildbucket for the build status
[23:43:01.741] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:01.741] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:27:0) after 1m0s
[23:44:02.125] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:27:0)
[23:44:02.125] Timer tick, asking Buildbucket for the build status
[23:44:02.268] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:02.268] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:28:0) after 1m0s
[23:45:02.552] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:28:0)
[23:45:02.552] Timer tick, asking Buildbucket for the build status
[23:45:02.618] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:02.618] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:29:0) after 1m0s
[23:46:02.718] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:29:0)
[23:46:02.718] Timer tick, asking Buildbucket for the build status
[23:46:02.827] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:02.828] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:30:0) after 1m0s
[23:47:02.891] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:30:0)
[23:47:02.891] Timer tick, asking Buildbucket for the build status
[23:47:03.422] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:03.422] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:31:0) after 1m0s
[23:48:03.452] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:31:0)
[23:48:03.452] Timer tick, asking Buildbucket for the build status
[23:48:03.541] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:03.541] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:32:0) after 1m0s
[23:49:03.643] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:32:0)
[23:49:03.643] Timer tick, asking Buildbucket for the build status
[23:49:03.765] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:03.765] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:33:0) after 1m0s
[23:50:03.798] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:33:0)
[23:50:03.798] Timer tick, asking Buildbucket for the build status
[23:50:04.097] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:04.097] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:34:0) after 1m0s
[23:51:04.089] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:34:0)
[23:51:04.089] Timer tick, asking Buildbucket for the build status
[23:51:04.259] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:04.259] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:35:0) after 1m0s
[23:52:04.310] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:35:0)
[23:52:04.310] Timer tick, asking Buildbucket for the build status
[23:52:04.390] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:04.390] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:36:0) after 1m0s
[23:53:04.398] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:36:0)
[23:53:04.398] Timer tick, asking Buildbucket for the build status
[23:53:04.490] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:04.490] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:37:0) after 1m0s
[23:54:04.569] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:37:0)
[23:54:04.569] Timer tick, asking Buildbucket for the build status
[23:54:04.754] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:04.754] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:38:0) after 1m0s
[23:55:04.899] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:38:0)
[23:55:04.899] Timer tick, asking Buildbucket for the build status
[23:55:04.969] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:04.969] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:39:0) after 1m0s
[23:56:05.003] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:39:0)
[23:56:05.003] Timer tick, asking Buildbucket for the build status
[23:56:05.076] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:05.077] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:40:0) after 1m0s
[23:57:05.174] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:40:0)
[23:57:05.174] Timer tick, asking Buildbucket for the build status
[23:57:05.398] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:05.398] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:41:0) after 1m0s
[23:58:05.523] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:41:0)
[23:58:05.523] Timer tick, asking Buildbucket for the build status
[23:58:05.693] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:05.693] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:42:0) after 1m0s
[23:59:05.897] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:42:0)
[23:59:05.897] Timer tick, asking Buildbucket for the build status
[23:59:06.011] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:06.011] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:43:0) after 1m0s
[00:00:06.276] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:43:0)
[00:00:06.276] Timer tick, asking Buildbucket for the build status
[00:00:06.440] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:06.442] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:44:0) after 1m0s
[00:01:07.021] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:44:0)
[00:01:07.021] Timer tick, asking Buildbucket for the build status
[00:01:07.178] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:07.178] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:45:0) after 1m0s
[00:02:07.218] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:45:0)
[00:02:07.218] Timer tick, asking Buildbucket for the build status
[00:02:07.381] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:07.381] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:46:0) after 1m0s
[00:03:07.456] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:46:0)
[00:03:07.456] Timer tick, asking Buildbucket for the build status
[00:03:07.649] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:07.649] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:47:0) after 1m0s
[00:04:07.782] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:47:0)
[00:04:07.782] Timer tick, asking Buildbucket for the build status
[00:04:08.261] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:08.261] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:48:0) after 1m0s
[00:05:08.325] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:48:0)
[00:05:08.325] Timer tick, asking Buildbucket for the build status
[00:05:09.134] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:09.134] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:49:0) after 1m0s
[00:06:09.194] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:49:0)
[00:06:09.194] Timer tick, asking Buildbucket for the build status
[00:06:09.559] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:09.559] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:50:0) after 1m0s
[00:07:09.729] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:50:0)
[00:07:09.730] Timer tick, asking Buildbucket for the build status
[00:07:09.899] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:09.899] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:51:0) after 1m0s
[00:08:10.095] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:51:0)
[00:08:10.095] Timer tick, asking Buildbucket for the build status
[00:08:10.738] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:10.738] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:52:0) after 1m0s
[00:09:10.777] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:52:0)
[00:09:10.777] Timer tick, asking Buildbucket for the build status
[00:09:10.909] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:10.909] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:53:0) after 1m0s
[00:10:11.016] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:53:0)
[00:10:11.016] Timer tick, asking Buildbucket for the build status
[00:10:11.180] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:11.181] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:54:0) after 1m0s
[00:11:11.193] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:54:0)
[00:11:11.193] Timer tick, asking Buildbucket for the build status
[00:11:11.450] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:11.450] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:55:0) after 1m0s
[00:12:11.503] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:55:0)
[00:12:11.503] Timer tick, asking Buildbucket for the build status
[00:12:11.730] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:11.730] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:56:0) after 1m0s
[00:13:11.775] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:56:0)
[00:13:11.775] Timer tick, asking Buildbucket for the build status
[00:13:11.928] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:11.928] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:57:0) after 1m0s
[00:14:11.989] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:57:0)
[00:14:11.989] Timer tick, asking Buildbucket for the build status
[00:14:12.557] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:12.557] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:58:0) after 1m0s
[00:15:12.568] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:58:0)
[00:15:12.568] Timer tick, asking Buildbucket for the build status
[00:15:12.726] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:12.726] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:59:0) after 1m0s
[00:16:12.745] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:59:0)
[00:16:12.745] Timer tick, asking Buildbucket for the build status
[00:16:12.860] Build 8864429444800797024: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:12.860] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9029859507705791024:60:0) after 1m0s
[00:16:13.943] Received PubSub notification, asking Buildbucket for the build status
[00:16:14.019] Build 8864429444800797024: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:16:14.019] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1604621772023881",
"created_by": "project:chromium",
"created_ts": "1604618331106303",
"id": "8864429444800797024",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9a749c24587de396d0e4ecfdd2b7670eef0f78ff\"}}",
"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\": \"9a749c24587de396d0e4ecfdd2b7670eef0f78ff\", \"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-76-1638\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 42616, \"got_angle_revision\": \"56ea854ec5e74b2f8e9b4ed7967b56c4c2a67bc9\", \"got_dawn_revision\": \"d42713de7aa3227f1feb405beddac4e37051b679\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"9a749c24587de396d0e4ecfdd2b7670eef0f78ff\", \"got_revision_cp\": \"refs/heads/master@{#824632}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"3476ca348bd0cd1223d815173ec528abbabd32f5\", \"got_v8_revision\": \"368dd750e2f684f0cb1f7527494676d69d7b8f76\", \"got_v8_revision_cp\": \"refs/heads/8.8.240@{#1}\", \"got_webrtc_revision\": \"42cafa5696f516712bbe6709fedeedffac61544c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32540}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9a749c24587de396d0e4ecfdd2b7670eef0f78ff\", \"swarm_hashes_refs/heads/master(at){#824632}_without_patch\": {\"absl_hardening_tests\": \"81c346e525eafd45c6c5a3ac9da13c728b2b753e\", \"accessibility_unittests\": \"448ee321461c31d5e8b84312edbc10b30cc0100d\", \"angle_unittests\": \"58805771ed9d106d89ff1a2544e7948fd374d6d8\", \"app_list_unittests\": \"e28651ef618824a575d1f95c88db39a8191f3f7d\", \"app_shell_unittests\": \"187e8ed39d592f455096a71126b9ffcd78ce7b32\", \"ash_unittests\": \"3cb003069fe182860323e07763eb4963bd884a35\", \"aura_unittests\": \"585991cdc10a1ad8a0a802c3a1ca0763536d2123\", \"base_unittests\": \"565345294367db22225ebeafca4b748e0a054a6f\", \"base_util_unittests\": \"513986a7595d8d4eaaeea0efc139cfd6288eb327\", \"blink_common_unittests\": \"bb8f12c2c90633188773fe8834fdaf9038cbacd4\", \"blink_fuzzer_unittests\": \"8a97fc45233f0be2e792049fbf737ccc2df54172\", \"blink_heap_unittests\": \"6dd42649f5d189a7695e15114862740d61b6543d\", \"blink_platform_unittests\": \"b3c2e2de0bd84d26acdc36d0a8cd7ebd793d8e6c\", \"blink_unittests\": \"4c7e0ea82b667869e72896e51a738d54a6e6de3d\", \"boringssl_crypto_tests\": \"6b4606b63ab07ff5b489792421d9324a0a707d17\", \"boringssl_ssl_tests\": \"e19d70869d8d209b7c59c15155e1a491921abef5\", \"browser_tests\": \"30dcb7f365b6b8810f64483b6e8e019775c7e176\", \"capture_unittests\": \"bce31dda9a04b43218ed45f52327fb54f3f8cc42\", \"cast_unittests\": \"76c95186e11eca0124b7f0558f92133a95d340c4\", \"cc_unittests\": \"d24b532e8bb1d362e30e2fb97dc3fbb686231f9f\", \"chrome_app_unittests\": \"8bc7eafa8ebe97f9921359ba0a5d56f64d03a90b\", \"chromedriver_unittests\": \"6e1fa7d3f4cd92559bbf5a99f317406a4e2ea2f6\", \"chromeos_components_unittests\": \"24e85eb4c1151e017f36b66bdef24c1506d481aa\", \"chromeos_unittests\": \"1d6d4e4e7c6e45ff0f0621633d70e9bf6a5fe2fa\", \"components_browsertests\": \"8116de1498d13aad68c2eef0044e742a34f00b2c\", \"components_unittests\": \"7a4621232c1cc45463f450411fd2501d388c7ee0\", \"compositor_unittests\": \"39433423c7fb8ccc8c33a327715172831158ac19\", \"content_browsertests\": \"cf39b8ad029e676297375a5b06ad4966f4f04bd1\", \"content_unittests\": \"91dbc4419a10f13834c49da781051a065db550af\", \"crypto_unittests\": \"1c3d9cd75801804d4f7724bcfe6ff86c121688ba\", \"dbus_unittests\": \"07327001f631ee6a36b5f75e52fe4e585ff3dc82\", \"device_unittests\": \"279aa29865950de9682fb7db6f86ee3ce606b711\", \"display_unittests\": \"84728cf4b6f044e821b71628a488ef72a9966582\", \"events_unittests\": \"8196c7789d2ce302164253a797ee616130b8fe16\", \"exo_unittests\": \"17d8a68d52ce65893c7b89ce25851d484edbc7f4\", \"extensions_browsertests\": \"75163ff73805fa248805f8ddba22dd49d1d505d7\", \"extensions_unittests\": \"f5a94a17dc6cc2892a20dae1f8f21dcc865b6440\", \"filesystem_service_unittests\": \"5be4f07b78c1b83838e1fe1878dac1366d37c892\", \"gcm_unit_tests\": \"1d762777ee7cda924f6d95e085b4500914372084\", \"gfx_unittests\": \"bddac653410126075c2d5d5f342db6f483e88d0a\", \"gin_unittests\": \"09dfb1acaf4687db7a4b03ce61f5b7ae24d2a0ff\", \"gl_unittests_ozone\": \"827c940fa615ff5a3b99140e51433803cce2a36d\", \"google_apis_unittests\": \"2aaeeb51ad62279f0a4c12af68d6babc9193477a\", \"gpu_unittests\": \"095fbf43de76c766d72c607fda9abc733cc1f640\", \"gwp_asan_unittests\": \"120b873323038b351c59e2a00e22a941c511ee80\", \"interactive_ui_tests\": \"52d4fa0f8f9d6e232e93281905522d8945548b3f\", \"ipc_tests\": \"76375780a377ef697eff9523697fb4bd64d184c8\", \"jingle_unittests\": \"a55b974e8050f7fc149466f8c6a93378a24e4fd8\", \"keyboard_unittests\": \"72a50ffe24c1bc3c4972cc7ace1e6f1c6cf06e4f\", \"latency_unittests\": \"a27c509ea8645aee69ef971579eae51ead94af1a\", \"libjingle_xmpp_unittests\": \"813a3218b1786f9323da73b6f3bc8d7ca5058ab6\", \"liburlpattern_unittests\": \"66e2748468b91d22a2943075c484dce377b7cb28\", \"media_blink_unittests\": \"da099e443f595a5f570e2caab65e26636f2216bb\", \"media_unittests\": \"47ae543aab100952134220d2326a1fd385827fe1\", \"message_center_unittests\": \"bce10480efbb07e56325b90f077fe9d72c28fbca\", \"midi_unittests\": \"bb7f4bdc889063d13dba17f034f31f825541e94e\", \"mojo_core_unittests\": \"40edcf912ef97eb0a0bc91f2395c2d243cfc2d34\", \"mojo_unittests\": \"13219ce1c19cfa0754ff4574ca0e5a09c1774dfc\", \"nacl_helper_nonsfi_unittests\": \"7776cb3c41d26ede9a21b62b7f3295b1e38f0846\", \"nacl_loader_unittests\": \"17a1e1ad2767f6032c6cfa5f95e354fb6d700474\", \"native_theme_unittests\": \"7b903b7843dcac43aa65a79ce15a363434e91441\", \"net_unittests\": \"fb73217ffcb37d41d0e63d270b3b3c5501bd9d17\", \"ozone_gl_unittests\": \"b6fe63a79cbf914a85f7d0a5e195d0260e2e44f3\", \"ozone_unittests\": \"3351f369ed0f3d0f4531a9162e2dbadd0384e916\", \"ozone_x11_unittests\": \"ecd0489e4afd8e9079a274fc151d1a129ab11c2e\", \"pdf_unittests\": \"04a2c05f5f63681bb996c624361e8d049441f6aa\", \"perfetto_unittests\": \"3b8d30a86e8ba694a0449403c4e7b64cc18582c4\", \"ppapi_unittests\": \"92ed972b9e1497033fc7308dc2dec92d6faa8df3\", \"printing_unittests\": \"44729e25d11c276d35acacf6c65d73a94ce97472\", \"remoting_unittests\": \"578e7a63c9c134fb87a403ae07da67ba85339f1f\", \"sandbox_linux_unittests\": \"e72754b73b099fb1b9922f75617606359f1ff1ad\", \"service_manager_unittests\": \"9c8be06781a1f95f15d90400c9052f93bf07504d\", \"services_unittests\": \"9c5f10fc4c35525217e506dc27558a242acd12ae\", \"shell_dialogs_unittests\": \"86105ebb958c8d54fe79899e1dfb9648710f3ae5\", \"skia_unittests\": \"ebd926c8acb8664b4cdfadaef36bfed534a2bf45\", \"snapshot_unittests\": \"bbe97e041299126f4306a50b7ef235f85b955750\", \"sql_unittests\": \"742cb650f385ecf3660f5ea8f1659fb18a45dd2c\", \"storage_unittests\": \"13aa6a40413c82074350c501f3864eb158e02d85\", \"sync_integration_tests\": \"b051aa464b6ce39cc93479628e12cc3f0571c1b0\", \"traffic_annotation_auditor_unittests\": \"ee132c8cdc83959a716bbfde3bea5db547a6d799\", \"ui_base_unittests\": \"5f68b4d1da0e07b4955dab36ad489616cdeeb5b5\", \"ui_chromeos_unittests\": \"1dd53c31077755daa602f6be7e57152f98738e1b\", \"ui_touch_selection_unittests\": \"27c9f957c64ea8e1bf1f424acce9e214b8b73b63\", \"unit_tests\": \"18e981f783c3c2427b6f1e86902ea37b1658f185\", \"url_unittests\": \"7d56d94095f8ed06561a41fd7ee1c730b6592914\", \"usage_time_limit_unittests\": \"342cbff4aa22a120d87d2645634048d73a0dd145\", \"views_unittests\": \"c3e255b61db44a7a59cdfe69e5e87fb16148b015\", \"viz_unittests\": \"6b8475362017dcceb477de2a326ffe7aabc2e677\", \"wayland_client_perftests\": \"1c0aad69e00c8ddc09176e8567d8710d69ec65fa\", \"wm_unittests\": \"acb501a6818dd9bd7ad9f022f20aea7b791253f4\", \"wtf_unittests\": \"bb4dc4199ecfcd1c0ecfc85fe5df071f6b086c0c\", \"zlib_unittests\": \"498a49e98d045a2547134bdfc239f511fae18913\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_953e900270162ec95f568ea264af47d43b9a29a15e01ede040ffa4d15b188919_v2\", \"builder_d2010e0a6a440fb89731b061caf7d596c92723d209ffefd62489cc037db4f7a7_v2\", \"builder_e47700e9abf1b62c06bb1feb4592b8e752d42e12f6359b4e93db4847c43379d6_v2\", \"builder_ee5c11e7a332f55fd7a24d7319a67a6829cd4b81b48e997032d91622b214e82b_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-76-1638\"], \"image\": [\"chrome-xenial-20102500-5da31bca5f9\"], \"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\": [\"5614-d862eaa\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1604618334078453",
"status": "COMPLETED",
"status_changed_ts": "1604621772678782",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42616",
"builder:linux-chromeos-rel",
"buildset:commit/git/9a749c24587de396d0e4ecfdd2b7670eef0f78ff",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9a749c24587de396d0e4ecfdd2b7670eef0f78ff",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029859507705791024",
"scheduler_job_id:chromium/linux-chromeos-rel",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8864429444800797024/+/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:4fb386324875ee10",
"user_agent:luci-scheduler"
],
"updated_ts": "1604621772728838",
"url": "https://ci.chromium.org/b/8864429444800797024",
"utcnow_ts": "1604621774009639"
}
}
[00:16:14.019] Invocation finished in 57m25.067334409s with status SUCCEEDED