[01:41:56.421] New invocation is queued and will start shortly
[01:41:57.699] Starting the invocation (attempt 1)
[01:41:57.732] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:41:57.732] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:41:57.732] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9031028263672660144",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"7c88c78342088341ca3cf576f794abd81e458be0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9031028263672660144",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/7c88c78342088341ca3cf576f794abd81e458be0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7c88c78342088341ca3cf576f794abd81e458be0",
"gitiles_ref:refs/heads/master"
]
}
[01:41:58.311] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1603503717849541",
"id": "8865598201511305840",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7c88c78342088341ca3cf576f794abd81e458be0\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1603503718191305",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42223",
"builder:linux-chromeos-rel",
"buildset:commit/git/7c88c78342088341ca3cf576f794abd81e458be0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7c88c78342088341ca3cf576f794abd81e458be0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9031028263672660144",
"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/8865598201511305840/+/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": "1603503718191457",
"url": "https://ci.chromium.org/b/8865598201511305840",
"utcnow_ts": "1603503718305171"
}
}
[01:41:58.311] Task URL: https://ci.chromium.org/b/8865598201511305840
[01:41:58.311] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:2:0) after 1m0s
[01:42:32.253] Received PubSub notification, asking Buildbucket for the build status
[01:42:34.210] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:58.509] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:2:0)
[01:42:58.509] Timer tick, asking Buildbucket for the build status
[01:42:58.629] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:58.629] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:4:0) after 1m0s
[01:43:59.485] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:4:0)
[01:43:59.485] Timer tick, asking Buildbucket for the build status
[01:43:59.972] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:59.972] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:5:0) after 1m0s
[01:45:00.052] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:5:0)
[01:45:00.052] Timer tick, asking Buildbucket for the build status
[01:45:00.276] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:00.276] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:6:0) after 1m0s
[01:46:00.358] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:6:0)
[01:46:00.358] Timer tick, asking Buildbucket for the build status
[01:46:00.489] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:00.489] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:7:0) after 1m0s
[01:47:00.595] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:7:0)
[01:47:00.595] Timer tick, asking Buildbucket for the build status
[01:47:00.702] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:00.702] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:8:0) after 1m0s
[01:48:01.549] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:8:0)
[01:48:01.549] Timer tick, asking Buildbucket for the build status
[01:48:01.658] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:01.658] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:9:0) after 1m0s
[01:49:02.026] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:9:0)
[01:49:02.027] Timer tick, asking Buildbucket for the build status
[01:49:02.188] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:02.188] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:10:0) after 1m0s
[01:50:02.303] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:10:0)
[01:50:02.303] Timer tick, asking Buildbucket for the build status
[01:50:02.368] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:02.368] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:11:0) after 1m0s
[01:51:02.784] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:11:0)
[01:51:02.784] Timer tick, asking Buildbucket for the build status
[01:51:02.856] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:02.856] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:12:0) after 1m0s
[01:52:02.877] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:12:0)
[01:52:02.877] Timer tick, asking Buildbucket for the build status
[01:52:03.054] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:03.054] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:13:0) after 1m0s
[01:53:03.142] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:13:0)
[01:53:03.142] Timer tick, asking Buildbucket for the build status
[01:53:03.491] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:03.491] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:14:0) after 1m0s
[01:54:03.543] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:14:0)
[01:54:03.543] Timer tick, asking Buildbucket for the build status
[01:54:03.628] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:03.628] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:15:0) after 1m0s
[01:55:03.783] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:15:0)
[01:55:03.783] Timer tick, asking Buildbucket for the build status
[01:55:03.857] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:03.857] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:16:0) after 1m0s
[01:56:03.902] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:16:0)
[01:56:03.902] Timer tick, asking Buildbucket for the build status
[01:56:04.099] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:04.099] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:17:0) after 1m0s
[01:57:04.194] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:17:0)
[01:57:04.194] Timer tick, asking Buildbucket for the build status
[01:57:04.365] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:04.365] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:18:0) after 1m0s
[01:58:04.497] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:18:0)
[01:58:04.497] Timer tick, asking Buildbucket for the build status
[01:58:04.699] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:04.699] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:19:0) after 1m0s
[01:59:04.725] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:19:0)
[01:59:04.725] Timer tick, asking Buildbucket for the build status
[01:59:04.987] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:04.987] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:20:0) after 1m0s
[02:00:05.267] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:20:0)
[02:00:05.267] Timer tick, asking Buildbucket for the build status
[02:00:05.348] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:05.349] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:21:0) after 1m0s
[02:01:05.505] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:21:0)
[02:01:05.505] Timer tick, asking Buildbucket for the build status
[02:01:05.625] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:05.625] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:22:0) after 1m0s
[02:02:05.746] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:22:0)
[02:02:05.746] Timer tick, asking Buildbucket for the build status
[02:02:05.864] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:05.864] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:23:0) after 1m0s
[02:03:06.081] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:23:0)
[02:03:06.081] Timer tick, asking Buildbucket for the build status
[02:03:06.176] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:06.176] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:24:0) after 1m0s
[02:04:06.455] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:24:0)
[02:04:06.455] Timer tick, asking Buildbucket for the build status
[02:04:06.525] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:06.525] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:25:0) after 1m0s
[02:05:06.628] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:25:0)
[02:05:06.628] Timer tick, asking Buildbucket for the build status
[02:05:06.740] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:06.740] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:26:0) after 1m0s
[02:06:06.830] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:26:0)
[02:06:06.830] Timer tick, asking Buildbucket for the build status
[02:06:06.902] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:06.902] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:27:0) after 1m0s
[02:07:06.979] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:27:0)
[02:07:06.979] Timer tick, asking Buildbucket for the build status
[02:07:07.551] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:07.551] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:28:0) after 1m0s
[02:08:07.663] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:28:0)
[02:08:07.663] Timer tick, asking Buildbucket for the build status
[02:08:07.761] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:07.761] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:29:0) after 1m0s
[02:09:07.906] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:29:0)
[02:09:07.906] Timer tick, asking Buildbucket for the build status
[02:09:07.972] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:07.972] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:30:0) after 1m0s
[02:10:08.281] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:30:0)
[02:10:08.281] Timer tick, asking Buildbucket for the build status
[02:10:08.363] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:08.363] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:31:0) after 1m0s
[02:11:08.550] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:31:0)
[02:11:08.550] Timer tick, asking Buildbucket for the build status
[02:11:08.781] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:08.781] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:32:0) after 1m0s
[02:12:08.836] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:32:0)
[02:12:08.836] Timer tick, asking Buildbucket for the build status
[02:12:08.947] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:08.947] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:33:0) after 1m0s
[02:13:09.003] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:33:0)
[02:13:09.004] Timer tick, asking Buildbucket for the build status
[02:13:09.070] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:09.070] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:34:0) after 1m0s
[02:14:09.197] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:34:0)
[02:14:09.197] Timer tick, asking Buildbucket for the build status
[02:14:09.271] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:09.271] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:35:0) after 1m0s
[02:15:09.381] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:35:0)
[02:15:09.381] Timer tick, asking Buildbucket for the build status
[02:15:09.448] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:09.448] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:36:0) after 1m0s
[02:16:09.554] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:36:0)
[02:16:09.554] Timer tick, asking Buildbucket for the build status
[02:16:09.713] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:09.713] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:37:0) after 1m0s
[02:17:09.806] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:37:0)
[02:17:09.806] Timer tick, asking Buildbucket for the build status
[02:17:09.944] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:09.944] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:38:0) after 1m0s
[02:18:09.986] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:38:0)
[02:18:09.986] Timer tick, asking Buildbucket for the build status
[02:18:10.084] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:10.084] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:39:0) after 1m0s
[02:19:10.110] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:39:0)
[02:19:10.110] Timer tick, asking Buildbucket for the build status
[02:19:10.170] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:10.170] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:40:0) after 1m0s
[02:20:10.249] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:40:0)
[02:20:10.249] Timer tick, asking Buildbucket for the build status
[02:20:10.327] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:10.327] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:41:0) after 1m0s
[02:21:10.457] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:41:0)
[02:21:10.457] Timer tick, asking Buildbucket for the build status
[02:21:10.523] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:10.523] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:42:0) after 1m0s
[02:22:10.595] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:42:0)
[02:22:10.595] Timer tick, asking Buildbucket for the build status
[02:22:10.700] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:10.700] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:43:0) after 1m0s
[02:23:10.888] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:43:0)
[02:23:10.888] Timer tick, asking Buildbucket for the build status
[02:23:10.952] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:10.952] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:44:0) after 1m0s
[02:24:11.062] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:44:0)
[02:24:11.062] Timer tick, asking Buildbucket for the build status
[02:24:11.145] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:11.145] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:45:0) after 1m0s
[02:25:11.236] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:45:0)
[02:25:11.236] Timer tick, asking Buildbucket for the build status
[02:25:11.298] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:11.298] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:46:0) after 1m0s
[02:26:11.401] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:46:0)
[02:26:11.401] Timer tick, asking Buildbucket for the build status
[02:26:11.465] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:11.465] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:47:0) after 1m0s
[02:27:11.575] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:47:0)
[02:27:11.575] Timer tick, asking Buildbucket for the build status
[02:27:11.701] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:11.701] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:48:0) after 1m0s
[02:28:11.721] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:48:0)
[02:28:11.721] Timer tick, asking Buildbucket for the build status
[02:28:11.921] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:11.921] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:49:0) after 1m0s
[02:29:11.996] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:49:0)
[02:29:11.996] Timer tick, asking Buildbucket for the build status
[02:29:12.154] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:12.154] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:50:0) after 1m0s
[02:30:12.228] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:50:0)
[02:30:12.228] Timer tick, asking Buildbucket for the build status
[02:30:12.456] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:12.456] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:51:0) after 1m0s
[02:31:12.518] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:51:0)
[02:31:12.518] Timer tick, asking Buildbucket for the build status
[02:31:12.577] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:12.577] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:52:0) after 1m0s
[02:32:13.035] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:52:0)
[02:32:13.035] Timer tick, asking Buildbucket for the build status
[02:32:13.150] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:13.150] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:53:0) after 1m0s
[02:33:13.642] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:53:0)
[02:33:13.642] Timer tick, asking Buildbucket for the build status
[02:33:14.135] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:14.135] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:54:0) after 1m0s
[02:34:15.038] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:54:0)
[02:34:15.038] Timer tick, asking Buildbucket for the build status
[02:34:15.216] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:15.216] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:55:0) after 1m0s
[02:35:15.345] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:55:0)
[02:35:15.345] Timer tick, asking Buildbucket for the build status
[02:35:15.597] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:15.597] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:56:0) after 1m0s
[02:36:15.788] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:56:0)
[02:36:15.788] Timer tick, asking Buildbucket for the build status
[02:36:16.193] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:16.193] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:57:0) after 1m0s
[02:37:16.374] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:57:0)
[02:37:16.374] Timer tick, asking Buildbucket for the build status
[02:37:16.706] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:16.706] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:58:0) after 1m0s
[02:38:16.850] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:58:0)
[02:38:16.850] Timer tick, asking Buildbucket for the build status
[02:38:17.519] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:17.519] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:59:0) after 1m0s
[02:39:18.020] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:59:0)
[02:39:18.020] Timer tick, asking Buildbucket for the build status
[02:39:18.284] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:18.284] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:60:0) after 1m0s
[02:40:18.356] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:60:0)
[02:40:18.356] Timer tick, asking Buildbucket for the build status
[02:40:18.513] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:18.513] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:61:0) after 1m0s
[02:41:18.661] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:61:0)
[02:41:18.661] Timer tick, asking Buildbucket for the build status
[02:41:18.791] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:18.791] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:62:0) after 1m0s
[02:42:18.929] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:62:0)
[02:42:18.929] Timer tick, asking Buildbucket for the build status
[02:42:19.055] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:19.055] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:63:0) after 1m0s
[02:43:19.209] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:63:0)
[02:43:19.209] Timer tick, asking Buildbucket for the build status
[02:43:19.362] Build 8865598201511305840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:19.362] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9031028263672660144:64:0) after 1m0s
[02:43:47.505] Received PubSub notification, asking Buildbucket for the build status
[02:43:47.925] Build 8865598201511305840: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[02:43:47.925] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1603507426895659",
"created_by": "project:chromium",
"created_ts": "1603503717849541",
"failure_reason": "BUILD_FAILURE",
"id": "8865598201511305840",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7c88c78342088341ca3cf576f794abd81e458be0\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- MagnifierE2ETest.MovesScreenMagnifierToActiveDescendant\"}, \"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\": \"7c88c78342088341ca3cf576f794abd81e458be0\", \"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-27-4vcg\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 42223, \"got_angle_revision\": \"7a0263547cda936b320d80c650b69d7522ee1a9f\", \"got_dawn_revision\": \"8901df8ffe29b3bf2ef3269edfd3a0e25fdf4b82\", \"got_nacl_revision\": \"0fabd6dc4222dfae664e55a84326e0c904f5b62c\", \"got_revision\": \"7c88c78342088341ca3cf576f794abd81e458be0\", \"got_revision_cp\": \"refs/heads/master@{#820525}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"df17a76102dfabb3f1bd6e51449cece9f77b45e3\", \"got_v8_revision\": \"73fe59b55662be0ff07e79da1ece8b9e42415dca\", \"got_v8_revision_cp\": \"refs/heads/8.8.171@{#1}\", \"got_webrtc_revision\": \"3d25935127400418ef6b5970f0edec5db01d2ba6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32478}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7c88c78342088341ca3cf576f794abd81e458be0\", \"swarm_hashes_refs/heads/master(at){#820525}_without_patch\": {\"absl_hardening_tests\": \"688545d994f6532d7f0b85189d8f075c04666cf9\", \"accessibility_unittests\": \"99f69f40004775d7d71449457035e35c29ad1aa0\", \"angle_unittests\": \"edf535c474337c6c4dfe3adea646df621b9c5ee1\", \"app_list_unittests\": \"2ac1ecf2f0ef5d14574238a12b3ccc0f2236f6ef\", \"app_shell_unittests\": \"c3e47d36484b4b5a4839c5eef8424ca50be8d604\", \"ash_unittests\": \"43c8e7fbdae94c906b2230ff4b2a99146a5fb1cb\", \"aura_unittests\": \"6c51243d56c0884a60014242c8eee3cf6a5da4bf\", \"base_unittests\": \"56c4bc2c66e6b7472c6d21ff121a76d0133e2d5c\", \"base_util_unittests\": \"663d99147321ca41b0dc4bdd5e9016d09cd69722\", \"blink_common_unittests\": \"a40ff53d919ab17ce9099ec800ff2afe4e7bff79\", \"blink_fuzzer_unittests\": \"b01dab8e5198462877d594505133bfc8259f9150\", \"blink_heap_unittests\": \"c893983ed4712600cbef76fe2fbbc2148eaedf48\", \"blink_platform_unittests\": \"0baeebbe33541fd0550eab11343a96c3127c1ffd\", \"blink_unittests\": \"cb5e2764ac8cfd00ff88e6f5ac94ebc27b6239a4\", \"boringssl_crypto_tests\": \"5623290ab35bb3a9e56d9c02ef041d8399cb2408\", \"boringssl_ssl_tests\": \"890ac51bfa579d02ed27dbcd8425714c0af2ed42\", \"browser_tests\": \"e8bcbeca04246cd7393aeb87a8bc74feae6e51ae\", \"capture_unittests\": \"84497cc8dfd8c4570a834061bff91f81e5336e5c\", \"cast_unittests\": \"a4517ecfc2e5a4a97af35d09b6e78f078e0a2ca7\", \"cc_unittests\": \"21699db23f4d011dbf9f63f9befceb2cc98d32de\", \"chrome_app_unittests\": \"021809f7eeb3104b6380dc7246ced5a43bdb3aaa\", \"chromedriver_unittests\": \"1624da716733fef7248508a286ae996fb812834c\", \"chromeos_components_unittests\": \"059e73a029861bd8fb514051b950be899a1b50aa\", \"chromeos_unittests\": \"67ea00f328d8454ae591b8e5e43eaa308dd1ec2e\", \"components_browsertests\": \"fe75c40059a5b4e67704b421ef2655479177e5b5\", \"components_unittests\": \"0194ebab9e6f59dafe84b7225227f04d367df85a\", \"compositor_unittests\": \"24951a74a3aec8598b5898340a3117fc2b50395e\", \"content_browsertests\": \"1857da0cbb8a9c5c1db7f97f74f2e673bc0f6760\", \"content_unittests\": \"9fdf85bcfcfb4a9d535d88f0eda1c9fe38ebf378\", \"crypto_unittests\": \"1b5dd23cfac7a6653dad131544295ff13eba92c5\", \"dbus_unittests\": \"8eb7d227c09c9fa029fee5cb948fe3cca71708d8\", \"device_unittests\": \"3e2fd7bc0776ad57e3e395c7df1f43279155bc5b\", \"display_unittests\": \"eec78ef0b4e605810f9491460477014183a4a9ef\", \"events_unittests\": \"7dd6738a7b3d70a784433d4ee1caa7c381ca8adc\", \"exo_unittests\": \"4047abc6b2bda633f0337a9d7b84b14e8609802b\", \"extensions_browsertests\": \"1c33d4815bd55ffb9f949172cbebae320a77846e\", \"extensions_unittests\": \"967a52082b746a1e073542c2c0351438cad56d54\", \"filesystem_service_unittests\": \"110cea0c2c474c12e8804765745c3297684ec362\", \"gcm_unit_tests\": \"bb643bd287656614e50ca99f5f71907cc5df2690\", \"gfx_unittests\": \"bb0c697ba6c8745e5bc33b3a8c5a93fa38358441\", \"gin_unittests\": \"6b5f99ec5703e0e7e7dd1e745326042351302b7b\", \"gl_unittests_ozone\": \"931353fe522bc882a3c785f05b3de194ff924edd\", \"google_apis_unittests\": \"c4b4141b6c242051a3af128502f987a561ed4700\", \"gpu_unittests\": \"e6d4c3b1a388e1ce47023f5ecb254280ae8bb428\", \"gwp_asan_unittests\": \"48e4085ea948aef88fec7f62fb96e8d5e9420e29\", \"interactive_ui_tests\": \"9e7a0617bf4d4c65e17ad0c22275347ac9e9b765\", \"ipc_tests\": \"3d79c078491eee4c48ed631d4fd4154808480700\", \"jingle_unittests\": \"69d979f3f480d2dddaa67fcc04c4da4ecd45405b\", \"keyboard_unittests\": \"be7e1716a22e740a1b17b37d4b7eddff95080775\", \"latency_unittests\": \"82240bcb76e393ccd0f7d46a4d81866dfc6ab092\", \"libjingle_xmpp_unittests\": \"1266a0eede82b2671dd66a5417c7e1c8653b8c09\", \"media_blink_unittests\": \"9cdf9df6343185b4dbf204d2997b51bf69553899\", \"media_unittests\": \"609d2b4d18102e5094e65da7c0d3064ae71f04bc\", \"message_center_unittests\": \"f6cab6fc9c3475f737f776b89ccda83c00fb7560\", \"midi_unittests\": \"dab0b1ccfe5b85065c27d38555fb7e75352acd3d\", \"mojo_core_unittests\": \"b52cc33e904567a6e6a8428deb0b4a57f2a29a7b\", \"mojo_unittests\": \"681e9f153ea70d7406838b7752a9c137951a91cc\", \"nacl_helper_nonsfi_unittests\": \"66cb5a98a4c30875ec3e22118c472788989ba1c0\", \"nacl_loader_unittests\": \"626eeb51e1c0e8b8d22cf7826349049b040bf7ed\", \"native_theme_unittests\": \"e38af3b6cdcdacc606d5832e82e9fd6b05b6fdd3\", \"net_unittests\": \"47f716b67dbb48bf6ddd412178b61a8359e7f5dc\", \"ozone_gl_unittests\": \"3ef00e45f0dd341b67ad8e4f2dae36a53e2f65e1\", \"ozone_unittests\": \"dc024af1ce0d24bf5d10289d458769f227c1f546\", \"ozone_x11_unittests\": \"1c442345dc139f58f94d4c111c7cde7cb9884aac\", \"pdf_unittests\": \"fe82196d142ddfab16d2ab08a9517ebd951a32ff\", \"perfetto_unittests\": \"650ae8effb6795b98a4f3d8b73dc4e60efa21f83\", \"ppapi_unittests\": \"f0c9bfa1e1d05d8da5e558697dfd22934fb77ff0\", \"printing_unittests\": \"9148b49d51d5873c6432432d3c2dbcfc3eb2e97a\", \"remoting_unittests\": \"9434eab56a618888387addb23f9f93b27dae0761\", \"sandbox_linux_unittests\": \"fea96530565e0495689ed51085234f0f4494f882\", \"service_manager_unittests\": \"6f20eb45feb29abff9467d9e5b74ea8d5d6882af\", \"services_unittests\": \"10b6c9675cd9864e0d6b6047606f397feb7f0efe\", \"shell_dialogs_unittests\": \"27cf567f13a1d8db8694fd387d6696db3face361\", \"skia_unittests\": \"fae8610e227261ca84706b85985bcceb0cf45808\", \"snapshot_unittests\": \"858f3271de9cffab611ad6fb7140dc97ddcd719f\", \"sql_unittests\": \"7ff19f23e7da379c1e49c06030f603fe8169a7d3\", \"storage_unittests\": \"bd21098dc829ab600f74acb7b22bbf4d74974289\", \"sync_integration_tests\": \"97d500b37eab40367fbd00c4000c177afd9f6cb5\", \"traffic_annotation_auditor_unittests\": \"68be0024a0a63dd6122db950735473907b6fefed\", \"ui_base_unittests\": \"9af6d617016654f92a53ff2af3495b8ba1f7b54c\", \"ui_chromeos_unittests\": \"0d24d381b6077dcd585872131c5ffeaa78de53ea\", \"ui_touch_selection_unittests\": \"42f05996b48bb1006eead9528cc3fe47e0c56cc6\", \"unit_tests\": \"7a40d163ae38b5a2fb99c746f2878a8fb42d16ed\", \"url_unittests\": \"ac74ef8e2bbb2e907ec44d67b2c5203cb61977a7\", \"usage_time_limit_unittests\": \"bc8678fde5f51ef189cc7dffad12397e2cb4445f\", \"views_unittests\": \"740f506e294cf799ec4cc2b82ebae95b060477f1\", \"viz_unittests\": \"8c12467f5886c07bcc3ba90e2cba40f84e91b4a8\", \"wayland_client_perftests\": \"8feb8ec3f83abe851663f7cc40b9ef3e0d9e28eb\", \"wm_unittests\": \"10206c3d97a850053cef5d1abbeaa2e7cdab0aef\", \"wtf_unittests\": \"d6d4a4940357ab6ec7e4dde9f0f8a20d95588323\", \"zlib_unittests\": \"6ca90be974f4fe5e8a10d9ae601dea0e2dff5ec2\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_32ded518776cda8cac391977d607693e2958c39df4b87a6f7ec8056ca6add5b1_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_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-27-4vcg\"], \"image\": [\"chrome-xenial-20101800-2d400e1627f\"], \"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\": [\"5612-26d29a7\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- MagnifierE2ETest.MovesScreenMagnifierToActiveDescendant\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1603503751272133",
"status": "COMPLETED",
"status_changed_ts": "1603503751839220",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/42223",
"builder:linux-chromeos-rel",
"buildset:commit/git/7c88c78342088341ca3cf576f794abd81e458be0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7c88c78342088341ca3cf576f794abd81e458be0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9031028263672660144",
"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/8865598201511305840/+/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:4f71169086d9d510",
"user_agent:luci-scheduler"
],
"updated_ts": "1603507427259557",
"url": "https://ci.chromium.org/b/8865598201511305840",
"utcnow_ts": "1603507427898380"
}
}
[02:43:47.925] Invocation finished in 1h1m51.520428763s with status FAILED