[04:28:31.363] New invocation is queued and will start shortly
[04:28:32.439] Starting the invocation (attempt 1)
[04:28:32.512] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:28:32.514] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:28:32.514] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021052116909858288",
"parameters_json": "{\"builder_name\":\"linux-chromeos-rel\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"cc484c290cab98865a656a34a510b023ae405753\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-rel",
"scheduler_invocation_id:9021052116909858288",
"scheduler_job_id:chromium/linux-chromeos-rel",
"user_agent:luci-scheduler",
"buildset:commit/git/cc484c290cab98865a656a34a510b023ae405753",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cc484c290cab98865a656a34a510b023ae405753",
"gitiles_ref:refs/heads/master"
]
}
[04:28:34.303] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613017712749985",
"id": "8855622054794355904",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cc484c290cab98865a656a34a510b023ae405753\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613017713922233",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45315",
"builder:linux-chromeos-rel",
"buildset:commit/git/cc484c290cab98865a656a34a510b023ae405753",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cc484c290cab98865a656a34a510b023ae405753",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021052116909858288",
"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/8855622054794355904/+/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": "1613017713922385",
"url": "https://ci.chromium.org/b/8855622054794355904",
"utcnow_ts": "1613017714283348"
}
}
[04:28:34.303] Task URL: https://ci.chromium.org/b/8855622054794355904
[04:28:34.303] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:2:0) after 1m0s
[04:28:56.478] Received PubSub notification, asking Buildbucket for the build status
[04:28:56.601] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:34.412] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:2:0)
[04:29:34.412] Timer tick, asking Buildbucket for the build status
[04:29:34.563] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:34.563] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:4:0) after 1m0s
[04:30:34.662] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:4:0)
[04:30:34.662] Timer tick, asking Buildbucket for the build status
[04:30:34.752] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:34.752] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:5:0) after 1m0s
[04:31:34.792] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:5:0)
[04:31:34.792] Timer tick, asking Buildbucket for the build status
[04:31:34.979] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:34.979] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:6:0) after 1m0s
[04:32:35.033] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:6:0)
[04:32:35.033] Timer tick, asking Buildbucket for the build status
[04:32:35.137] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:35.137] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:7:0) after 1m0s
[04:33:35.111] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:7:0)
[04:33:35.111] Timer tick, asking Buildbucket for the build status
[04:33:35.256] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:35.256] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:8:0) after 1m0s
[04:34:35.276] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:8:0)
[04:34:35.276] Timer tick, asking Buildbucket for the build status
[04:34:35.379] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:35.379] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:9:0) after 1m0s
[04:35:35.398] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:9:0)
[04:35:35.398] Timer tick, asking Buildbucket for the build status
[04:35:35.467] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:35.467] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:10:0) after 1m0s
[04:36:35.498] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:10:0)
[04:36:35.498] Timer tick, asking Buildbucket for the build status
[04:36:35.709] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:35.709] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:11:0) after 1m0s
[04:37:35.715] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:11:0)
[04:37:35.716] Timer tick, asking Buildbucket for the build status
[04:37:35.816] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:35.816] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:12:0) after 1m0s
[04:38:35.825] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:12:0)
[04:38:35.825] Timer tick, asking Buildbucket for the build status
[04:38:36.041] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:36.041] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:13:0) after 1m0s
[04:39:36.058] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:13:0)
[04:39:36.058] Timer tick, asking Buildbucket for the build status
[04:39:36.190] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:36.190] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:14:0) after 1m0s
[04:40:36.208] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:14:0)
[04:40:36.208] Timer tick, asking Buildbucket for the build status
[04:40:36.279] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:36.280] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:15:0) after 1m0s
[04:41:36.303] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:15:0)
[04:41:36.303] Timer tick, asking Buildbucket for the build status
[04:41:36.392] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:36.392] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:16:0) after 1m0s
[04:42:36.415] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:16:0)
[04:42:36.415] Timer tick, asking Buildbucket for the build status
[04:42:36.494] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:36.494] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:17:0) after 1m0s
[04:43:36.536] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:17:0)
[04:43:36.536] Timer tick, asking Buildbucket for the build status
[04:43:36.623] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:36.623] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:18:0) after 1m0s
[04:44:36.807] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:18:0)
[04:44:36.807] Timer tick, asking Buildbucket for the build status
[04:44:37.103] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:37.103] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:19:0) after 1m0s
[04:45:37.156] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:19:0)
[04:45:37.156] Timer tick, asking Buildbucket for the build status
[04:45:37.278] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:37.279] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:20:0) after 1m0s
[04:46:37.413] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:20:0)
[04:46:37.413] Timer tick, asking Buildbucket for the build status
[04:46:37.498] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:37.498] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:21:0) after 1m0s
[04:47:37.552] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:21:0)
[04:47:37.552] Timer tick, asking Buildbucket for the build status
[04:47:37.660] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:37.660] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:22:0) after 1m0s
[04:48:37.708] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:22:0)
[04:48:37.708] Timer tick, asking Buildbucket for the build status
[04:48:37.814] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:37.814] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:23:0) after 1m0s
[04:49:37.899] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:23:0)
[04:49:37.899] Timer tick, asking Buildbucket for the build status
[04:49:38.097] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:38.097] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:24:0) after 1m0s
[04:50:38.205] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:24:0)
[04:50:38.205] Timer tick, asking Buildbucket for the build status
[04:50:38.280] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:38.280] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:25:0) after 1m0s
[04:51:38.321] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:25:0)
[04:51:38.321] Timer tick, asking Buildbucket for the build status
[04:51:38.422] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:38.422] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:26:0) after 1m0s
[04:52:38.427] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:26:0)
[04:52:38.427] Timer tick, asking Buildbucket for the build status
[04:52:38.521] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:38.521] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:27:0) after 1m0s
[04:53:38.545] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:27:0)
[04:53:38.545] Timer tick, asking Buildbucket for the build status
[04:53:38.626] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:38.626] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:28:0) after 1m0s
[04:54:38.629] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:28:0)
[04:54:38.629] Timer tick, asking Buildbucket for the build status
[04:54:38.774] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:38.774] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:29:0) after 1m0s
[04:55:38.776] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:29:0)
[04:55:38.776] Timer tick, asking Buildbucket for the build status
[04:55:39.138] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:39.138] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:30:0) after 1m0s
[04:56:39.238] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:30:0)
[04:56:39.238] Timer tick, asking Buildbucket for the build status
[04:56:39.355] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:39.355] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:31:0) after 1m0s
[04:57:39.368] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:31:0)
[04:57:39.368] Timer tick, asking Buildbucket for the build status
[04:57:39.486] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:39.486] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:32:0) after 1m0s
[04:58:39.505] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:32:0)
[04:58:39.505] Timer tick, asking Buildbucket for the build status
[04:58:39.590] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:39.590] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:33:0) after 1m0s
[04:59:39.613] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:33:0)
[04:59:39.613] Timer tick, asking Buildbucket for the build status
[04:59:39.722] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:39.722] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:34:0) after 1m0s
[05:00:39.731] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:34:0)
[05:00:39.731] Timer tick, asking Buildbucket for the build status
[05:00:40.098] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:40.098] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:35:0) after 1m0s
[05:01:40.111] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:35:0)
[05:01:40.111] Timer tick, asking Buildbucket for the build status
[05:01:40.246] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:40.246] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:36:0) after 1m0s
[05:02:40.297] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:36:0)
[05:02:40.297] Timer tick, asking Buildbucket for the build status
[05:02:40.390] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:40.390] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:37:0) after 1m0s
[05:03:40.433] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:37:0)
[05:03:40.433] Timer tick, asking Buildbucket for the build status
[05:03:40.518] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:40.519] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:38:0) after 1m0s
[05:04:40.569] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:38:0)
[05:04:40.569] Timer tick, asking Buildbucket for the build status
[05:04:40.730] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:40.730] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:39:0) after 1m0s
[05:05:40.780] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:39:0)
[05:05:40.780] Timer tick, asking Buildbucket for the build status
[05:05:40.870] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:40.870] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:40:0) after 1m0s
[05:06:40.904] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:40:0)
[05:06:40.904] Timer tick, asking Buildbucket for the build status
[05:06:41.186] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:41.186] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:41:0) after 1m0s
[05:07:41.236] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:41:0)
[05:07:41.236] Timer tick, asking Buildbucket for the build status
[05:07:41.331] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:41.331] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:42:0) after 1m0s
[05:08:41.378] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:42:0)
[05:08:41.378] Timer tick, asking Buildbucket for the build status
[05:08:41.476] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:41.476] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:43:0) after 1m0s
[05:09:41.493] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:43:0)
[05:09:41.493] Timer tick, asking Buildbucket for the build status
[05:09:41.587] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:41.587] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:44:0) after 1m0s
[05:10:41.610] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:44:0)
[05:10:41.610] Timer tick, asking Buildbucket for the build status
[05:10:41.921] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:41.921] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:45:0) after 1m0s
[05:11:42.127] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:45:0)
[05:11:42.127] Timer tick, asking Buildbucket for the build status
[05:11:42.213] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:42.213] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:46:0) after 1m0s
[05:12:42.364] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:46:0)
[05:12:42.364] Timer tick, asking Buildbucket for the build status
[05:12:42.460] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:42.460] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:47:0) after 1m0s
[05:13:42.491] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:47:0)
[05:13:42.491] Timer tick, asking Buildbucket for the build status
[05:13:43.868] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:43.868] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:48:0) after 1m0s
[05:14:43.947] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:48:0)
[05:14:43.947] Timer tick, asking Buildbucket for the build status
[05:14:44.117] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:44.117] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:49:0) after 1m0s
[05:15:44.092] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:49:0)
[05:15:44.092] Timer tick, asking Buildbucket for the build status
[05:15:44.199] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:44.199] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:50:0) after 1m0s
[05:16:44.226] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:50:0)
[05:16:44.226] Timer tick, asking Buildbucket for the build status
[05:16:44.363] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:44.363] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:51:0) after 1m0s
[05:17:44.490] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:51:0)
[05:17:44.490] Timer tick, asking Buildbucket for the build status
[05:17:44.818] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:44.818] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:52:0) after 1m0s
[05:18:44.836] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:52:0)
[05:18:44.836] Timer tick, asking Buildbucket for the build status
[05:18:44.936] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:44.936] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:53:0) after 1m0s
[05:19:45.077] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:53:0)
[05:19:45.077] Timer tick, asking Buildbucket for the build status
[05:19:45.171] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:45.171] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:54:0) after 1m0s
[05:20:45.278] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:54:0)
[05:20:45.278] Timer tick, asking Buildbucket for the build status
[05:20:45.685] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:45.685] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:55:0) after 1m0s
[05:21:45.868] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:55:0)
[05:21:45.868] Timer tick, asking Buildbucket for the build status
[05:21:45.975] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:45.975] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:56:0) after 1m0s
[05:22:45.966] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:56:0)
[05:22:45.966] Timer tick, asking Buildbucket for the build status
[05:22:46.053] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:46.053] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:57:0) after 1m0s
[05:23:46.061] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:57:0)
[05:23:46.061] Timer tick, asking Buildbucket for the build status
[05:23:46.165] Build 8855622054794355904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:46.165] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-rel:9021052116909858288:58:0) after 1m0s
[05:24:37.198] Received PubSub notification, asking Buildbucket for the build status
[05:24:37.289] Build 8855622054794355904: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[05:24:37.289] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613021075863028",
"created_by": "project:chromium",
"created_ts": "1613017712749985",
"failure_reason": "BUILD_FAILURE",
"id": "8855622054794355904",
"parameters_json": "{\"builder_name\": \"linux-chromeos-rel\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cc484c290cab98865a656a34a510b023ae405753\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- MediaEngagementBrowserTest.DoNotRecordEngagement_PlaybackStopped\"}, \"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\": \"cc484c290cab98865a656a34a510b023ae405753\", \"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-central1-b-8-162-831p\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-rel\", \"buildnumber\": 45315, \"got_angle_revision\": \"a8a2a71b3ab5e535def6239997f6f24da918556b\", \"got_dawn_revision\": \"7e80cce1a9065d60522d470d24526394d642e6d5\", \"got_nacl_revision\": \"82ac8c0a6f0d3ffc843c693dd5149a356b866ae5\", \"got_revision\": \"cc484c290cab98865a656a34a510b023ae405753\", \"got_revision_cp\": \"refs/heads/master@{#852991}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"673a7fe5c3ff6686c98951cafb24a93ca5bd1303\", \"got_v8_revision\": \"45ea66edc82419c79bba183762142785bb5a72e2\", \"got_v8_revision_cp\": \"refs/heads/9.0.162@{#1}\", \"got_webrtc_revision\": \"8bf61a3071b7306860261eadf43c157ce3ffe4fe\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33223}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-inverse-fieldtrials-fyi-rel\", \"tryserver.chromium.chromiumos:linux-chromeos-rel\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cc484c290cab98865a656a34a510b023ae405753\", \"swarm_hashes_refs/heads/master(at){#852991}_without_patch\": {\"absl_hardening_tests\": \"52213ec31df24cd53817f39ef52dd1688c3893ec\", \"accessibility_unittests\": \"d5c0d4472270bceb6582a4212de74e6bb9c02f34\", \"angle_unittests\": \"529c1f4abb7818885ff23b56ef27f33eefc50792\", \"app_list_unittests\": \"f01fc80e4a8218d23bb95035b7d9d531f72feab4\", \"app_shell_unittests\": \"75b8bfe715257c727f060361311bf05f3ecc28d9\", \"ash_unittests\": \"e2a279727be3cdd7cc4f7f7e145a1e55f4a76e20\", \"aura_unittests\": \"5d1a21ba114f09f612fe6bd8a736c7404ebdd5b6\", \"base_unittests\": \"1bb9eba64c3a989178740e4d108c2d7768110b38\", \"base_util_unittests\": \"43725c6499d69792bc89130e5109186ed93baef9\", \"blink_common_unittests\": \"fc2e744ff935f5b63c5eefb5ed0272dbba04ed78\", \"blink_fuzzer_unittests\": \"590686c92c85dde4bd6c2d54092ef5add0338159\", \"blink_heap_unittests\": \"d49ef91b948b0831071ec0946a4f58288c387d83\", \"blink_platform_unittests\": \"abe9c93ce4d5102fca48481b8353d0a10a015df0\", \"blink_unittests\": \"f686d291bd761fe855ca15f92473a7b2bd479639\", \"boringssl_crypto_tests\": \"5f75b39c7aa748f3f49f095d56f446485d098d83\", \"boringssl_ssl_tests\": \"84f97b3ffda307803a8fcb5280970fdf0d37c842\", \"browser_tests\": \"d5d687563743da002578b5b98c52e4d1b0141529\", \"capture_unittests\": \"778dc69a8b9cf13c0c2c01cb961170741154bf95\", \"cast_unittests\": \"8870a9c70c0092d3884a135b0aebe0c0588ead35\", \"cc_unittests\": \"5144d56708becef9300b4dab382b13f57f3df415\", \"chrome_app_unittests\": \"560a0e34da39768599003964298a91e5366649a0\", \"chromedriver_unittests\": \"01b82b2b814e6e1b828abd8f80463be9eac0e073\", \"chromeos_components_unittests\": \"91189b9f70bc1f9db3efbc4acf2bf690b5605e5a\", \"chromeos_unittests\": \"3a57a983995c60c56974d9e4ae3740fcfaf11c64\", \"components_browsertests\": \"f9a575ebb43cb0e10661a8d412d766c07b59ea3d\", \"components_unittests\": \"c2546fd6adbcc85b3dc894c4e81c63175b5ba4fd\", \"compositor_unittests\": \"f19a52ae6ed1cf11d7a50e632882629a1090cd66\", \"content_browsertests\": \"e0634663b2b2be338b7307e70acae4b0efb4e65c\", \"content_unittests\": \"440667dd75f40a6d1c2d4cbc6f239d77d7beec9b\", \"crashpad_tests\": \"329e6c700a9eeffd3f3400658dc5334b263a4efb\", \"crypto_unittests\": \"f219f146c338185eac4b0957f827280c851ba9d6\", \"dbus_unittests\": \"0800ee2419cf644fd6d9bd2a6a66826fa9bddf31\", \"device_unittests\": \"74c722e1d8f922b1f459889c9e8f8aaf07802df4\", \"display_unittests\": \"d819abc34434490884978b963e665f11148756bb\", \"events_unittests\": \"64ffc44752ef37a1cbc9d19d281887145bae7767\", \"exo_unittests\": \"20c887d5a8ad67a44cae8abc3a5472e183af8d52\", \"extensions_browsertests\": \"b9e7678031b526474880b61174471437c910cd35\", \"extensions_unittests\": \"e8f6a4118088d086a0a2eed9d6dc5284fe8b233a\", \"filesystem_service_unittests\": \"ab9a7cf69c3883e9ff9c493762ccbe045b4d4c5e\", \"gcm_unit_tests\": \"64b4cea31e9e7807859952cca4d0ca512fd86aba\", \"gfx_unittests\": \"a6009d8e2f024a90d6f26e924d2933993a11e881\", \"gin_unittests\": \"3e38574666287f07bac214bad53f29b7dd3cef3d\", \"gl_unittests_ozone\": \"7e7257a2b95355a89472e4cd77b7f1dc08ea3e25\", \"google_apis_unittests\": \"058262661c6f87dc5617abb69fa61d50071ac478\", \"gpu_unittests\": \"c0bf25c7e85ae7b4cd34c3456f9f65e800df85a9\", \"gwp_asan_unittests\": \"29f2f0de6b8d46f054ed4db6a3f7bf0917180055\", \"interactive_ui_tests\": \"49c3cd953c48866350f4d609e6a80bcb44cf76e2\", \"ipc_tests\": \"4e69e09205b070ff0f350cd88e2c405d6637a7f2\", \"jingle_unittests\": \"3bb48157d0412ab11284894db3b322b2aa2a880b\", \"keyboard_unittests\": \"79237f3c82e9dc30b2af37237658b7d2a720a47d\", \"latency_unittests\": \"df9fd01d79e0de1b01523bd57d33fb3d05461ae8\", \"libjingle_xmpp_unittests\": \"d14bd1137c2fe788f825142f08358ad08588fd9f\", \"liburlpattern_unittests\": \"982bf2bcac4fe6f5da6bfaf56f837ddb798da170\", \"media_blink_unittests\": \"b961cf33dedcf839697763a2c10cbbd679d881a5\", \"media_unittests\": \"a243520af346b83f4fb2d7eaf37a77cf8e3a5b19\", \"message_center_unittests\": \"5b6ef91da515ae64ab437c23502de39df9f85add\", \"midi_unittests\": \"c2fb1210b803a079a20731025a2d2c190eb1540c\", \"mojo_core_unittests\": \"7ddc82bef77a856c23cc1bcd595fd818fafce567\", \"mojo_unittests\": \"d6041a719149139901846e649b19e02900aa29b4\", \"nacl_helper_nonsfi_unittests\": \"0bc636ae27f8e1d56f7e2142821417b8eb1e9a1d\", \"nacl_loader_unittests\": \"b8bde0c8b6cfdf8f0e6e0a00d0edbe29067333ee\", \"native_theme_unittests\": \"eb6e9089859e788cf1d5d5b6f069c65030e1634b\", \"net_unittests\": \"711c13590bee57da3fdf50c0dd24540c4157a2ea\", \"ozone_gl_unittests\": \"5491f340fc64ec120ffea8b42119f01807fe02eb\", \"ozone_unittests\": \"500d5df5f44f7ca30428c470aaf037703c59e79d\", \"ozone_x11_unittests\": \"bf0fe28d63e133a73dfc4ceaf6384ef3c8d3e30d\", \"pdf_unittests\": \"369139e358c753d60b4c90175669a18ffaeb51dd\", \"perfetto_unittests\": \"5ede0d97012fe79c79056dba74cfcccba4d74703\", \"ppapi_unittests\": \"3850665a1e87ca13a9eeb1d906a82a5c75c57300\", \"printing_unittests\": \"16e4f7aa60b475c2d125a5a510561714595d4fe1\", \"remoting_unittests\": \"e5527cc8bdbea1c79e07fc5d1cfb64e027d61777\", \"sandbox_linux_unittests\": \"8d2a5d8816604731e0b011fe34b20e963a7bb28d\", \"service_manager_unittests\": \"c496fc6bb408931cbdad91fe06a34077f1d56096\", \"services_unittests\": \"c3648042358c5a61ba8dd1de6a1249aec254346c\", \"shell_dialogs_unittests\": \"e6f7c165b98019732fe73ce1669a6af115d1af7a\", \"shell_encryption_unittests\": \"29c7b7656b339fbddfb036e52f37445356c65e30\", \"skia_unittests\": \"2a5d6eb9b43d51814e14f53bc8e0d7457d48890a\", \"snapshot_unittests\": \"472ec8665352266646669afb957c03be0737940a\", \"sql_unittests\": \"627d17721b1140b14a641f88b2e4ce5cf762ec07\", \"storage_unittests\": \"ba01c52994f91945f4f3279af32703102f069518\", \"sync_integration_tests\": \"96230393e899d25cf7a8924c26ae085b9358f43d\", \"traffic_annotation_auditor_unittests\": \"14ea21761b40f4aae20e547b4b1b3b80d870c6d7\", \"ui_base_unittests\": \"4d9462095e8fed67e1271c18748cf4929170c368\", \"ui_chromeos_unittests\": \"be44a24927cc8800f7bbc7cbcfed335bd5c422c1\", \"ui_touch_selection_unittests\": \"b1e4ebda79db44574e67958b1de1747fcee31cd7\", \"unit_tests\": \"43d7e5dff0d5fbb9d845eedee96a52d1fd02e619\", \"url_unittests\": \"400ce1e549195bf87f6f0ad669b8ee8bf13637f1\", \"usage_time_limit_unittests\": \"f06af44665b326cb44641d0c4bb16ef8f0f73ce3\", \"views_unittests\": \"241da7fb3296512e72835db70e175a775340473e\", \"viz_unittests\": \"bfefeaea7aff6cb1b88a5e65a8bba9a2492ce72d\", \"wayland_client_perftests\": \"886892698b6f42800378d63994c108a772709855\", \"wm_unittests\": \"6e200e816993b4bae2b7a070e5b96f5b62f1104c\", \"wtf_unittests\": \"ac4052ea4668db96a5f85b0dd759de4fec260287\", \"zlib_unittests\": \"1c1f387a5db45c5be777ca2e2621599d59efd8b0\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_151f80801b9f29276c11c5f05dab27ce5673f9c9ba442aaf2cf20aab9215f522_v2\", \"builder_678d8b556ed6d2ea4aa43b80debb7b547a1d6d4433ddff28e80ab170912c760d_v2\", \"builder_6ab0eeab06ceac077c4f5e67fdebd04a4013e6231a211afb401025a2c2ae11ff_v2\", \"builder_94fa3119a28f4651cdd4fc5b981a64c69b26c151212072333439362290628993_v2\", \"builder_a15c792d02d243f2865d956ab51326d361a8fcfed3c44b30d209df7676d4718c_v2\", \"builder_bcc6b3e5c0858529730294da730af8ea17f2c5c79b8b0dc7af4e722a8412ac73_v2\", \"builder_d7cc7c59327c05146a034898ae78722818cd22c61c9435c4c4348e98bea5a132_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-central1-b-8-162-831p\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"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\": [\"5685-1877293\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- MediaEngagementBrowserTest.DoNotRecordEngagement_PlaybackStopped\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1613017735738760",
"status": "COMPLETED",
"status_changed_ts": "1613017736237070",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-rel/45315",
"builder:linux-chromeos-rel",
"buildset:commit/git/cc484c290cab98865a656a34a510b023ae405753",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cc484c290cab98865a656a34a510b023ae405753",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021052116909858288",
"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/8855622054794355904/+/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:51a82ad154684a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1613021076701405",
"url": "https://ci.chromium.org/b/8855622054794355904",
"utcnow_ts": "1613021077279961"
}
}
[05:24:37.289] Invocation finished in 56m5.94085426s with status FAILED