[06:10:44.513] New invocation is queued and will start shortly
[06:10:45.601] Starting the invocation (attempt 1)
[06:10:45.637] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:10:45.638] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:10:45.638] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9017874792013287056",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0ab1b0e5191a2eadf6ad581f808c523104076ede\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9017874792013287056",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/0ab1b0e5191a2eadf6ad581f808c523104076ede",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0ab1b0e5191a2eadf6ad581f808c523104076ede",
"gitiles_ref:refs/heads/master"
]
}
[06:10:46.355] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1616047845779604",
"id": "8852444730022631632",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0ab1b0e5191a2eadf6ad581f808c523104076ede\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1616047846239611",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/23031",
"builder:linux-chromeos-dbg",
"buildset:commit/git/0ab1b0e5191a2eadf6ad581f808c523104076ede",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0ab1b0e5191a2eadf6ad581f808c523104076ede",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017874792013287056",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8852444730022631632/+/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": "1616047846239723",
"url": "https://ci.chromium.org/b/8852444730022631632",
"utcnow_ts": "1616047846345280"
}
}
[06:10:46.355] Task URL: https://ci.chromium.org/b/8852444730022631632
[06:10:46.355] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:2:0) after 1m0s
[06:10:49.730] Received PubSub notification, asking Buildbucket for the build status
[06:10:49.759] Build status: STARTED
[06:11:46.382] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:2:0)
[06:11:46.382] Timer tick, asking Buildbucket for the build status
[06:11:46.458] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:46.458] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:4:0) after 1m0s
[06:12:46.478] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:4:0)
[06:12:46.478] Timer tick, asking Buildbucket for the build status
[06:12:46.546] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:46.546] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:5:0) after 1m0s
[06:13:46.566] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:5:0)
[06:13:46.596] Build status: STARTED
[06:13:46.596] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:6:0) after 9m54s
[06:23:40.612] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:6:0)
[06:23:40.612] Timer tick, asking Buildbucket for the build status
[06:23:40.870] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:40.870] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:7:0) after 1m0s
[06:24:40.892] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:7:0)
[06:24:40.892] Timer tick, asking Buildbucket for the build status
[06:24:40.995] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:40.995] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:8:0) after 1m0s
[06:25:41.032] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:8:0)
[06:25:41.032] Timer tick, asking Buildbucket for the build status
[06:25:41.100] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:41.100] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:9:0) after 1m0s
[06:26:41.124] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:9:0)
[06:26:41.331] Build status: STARTED
[06:26:41.331] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:10:0) after 3m49s
[06:30:30.349] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:10:0)
[06:30:30.592] Build status: STARTED
[06:30:30.592] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:11:0) after 7m46s
[06:38:16.691] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:11:0)
[06:38:16.691] Timer tick, asking Buildbucket for the build status
[06:38:16.770] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:16.770] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:12:0) after 1m0s
[06:39:16.928] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:12:0)
[06:39:16.928] Timer tick, asking Buildbucket for the build status
[06:39:16.999] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:16.999] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:13:0) after 1m0s
[06:40:17.078] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:13:0)
[06:40:17.078] Timer tick, asking Buildbucket for the build status
[06:40:17.151] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:17.151] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:14:0) after 1m0s
[06:41:17.188] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:14:0)
[06:41:17.224] Build status: STARTED
[06:41:17.224] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:15:0) after 1m33s
[06:42:50.242] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:15:0)
[06:42:50.311] Build status: STARTED
[06:42:50.311] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:16:0) after 9m2s
[06:51:52.336] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:16:0)
[06:51:52.363] Build status: STARTED
[06:51:52.363] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:17:0) after 7m25s
[06:59:17.515] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:17:0)
[06:59:17.515] Timer tick, asking Buildbucket for the build status
[06:59:17.640] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:17.640] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:18:0) after 1m0s
[07:00:17.763] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:18:0)
[07:00:17.763] Timer tick, asking Buildbucket for the build status
[07:00:17.856] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:17.856] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:19:0) after 1m0s
[07:01:21.505] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:19:0)
[07:01:21.505] Timer tick, asking Buildbucket for the build status
[07:01:21.749] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:21.749] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:20:0) after 1m0s
[07:02:21.778] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:20:0)
[07:02:21.778] Timer tick, asking Buildbucket for the build status
[07:02:21.974] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:21.974] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:21:0) after 1m0s
[07:03:22.004] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:21:0)
[07:03:22.040] Build status: STARTED
[07:03:22.040] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:22:0) after 4m6s
[07:07:28.071] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:22:0)
[07:07:28.071] Timer tick, asking Buildbucket for the build status
[07:07:28.176] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:28.176] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:23:0) after 1m0s
[07:08:28.199] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:23:0)
[07:08:28.199] Timer tick, asking Buildbucket for the build status
[07:08:28.603] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:28.603] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:24:0) after 1m0s
[07:09:28.620] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:24:0)
[07:09:28.651] Build status: STARTED
[07:09:28.651] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:25:0) after 5m46s
[07:15:14.739] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:25:0)
[07:15:14.739] Timer tick, asking Buildbucket for the build status
[07:15:15.029] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:15.029] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:26:0) after 1m0s
[07:16:15.092] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:26:0)
[07:16:15.092] Timer tick, asking Buildbucket for the build status
[07:16:15.193] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:15.193] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:27:0) after 1m0s
[07:17:15.388] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:27:0)
[07:17:15.388] Timer tick, asking Buildbucket for the build status
[07:17:15.790] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:15.790] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:28:0) after 1m0s
[07:18:15.913] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:28:0)
[07:18:15.913] Timer tick, asking Buildbucket for the build status
[07:18:16.003] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:16.003] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:29:0) after 1m0s
[07:19:16.126] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:29:0)
[07:19:16.126] Timer tick, asking Buildbucket for the build status
[07:19:16.492] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:16.492] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:30:0) after 1m0s
[07:20:16.537] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:30:0)
[07:20:16.537] Timer tick, asking Buildbucket for the build status
[07:20:16.601] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:16.602] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:31:0) after 1m0s
[07:21:16.628] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:31:0)
[07:21:16.628] Timer tick, asking Buildbucket for the build status
[07:21:16.721] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:16.721] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:32:0) after 1m0s
[07:22:16.746] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:32:0)
[07:22:16.746] Timer tick, asking Buildbucket for the build status
[07:22:16.817] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:16.817] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:33:0) after 1m0s
[07:23:16.872] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:33:0)
[07:23:16.872] Timer tick, asking Buildbucket for the build status
[07:23:16.941] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:16.941] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:34:0) after 1m0s
[07:24:16.986] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:34:0)
[07:24:16.986] Timer tick, asking Buildbucket for the build status
[07:24:17.204] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:17.204] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:35:0) after 1m0s
[07:25:17.320] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:35:0)
[07:25:17.320] Timer tick, asking Buildbucket for the build status
[07:25:17.741] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:17.741] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:36:0) after 1m0s
[07:26:17.739] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:36:0)
[07:26:17.739] Timer tick, asking Buildbucket for the build status
[07:26:18.014] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:18.014] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:37:0) after 1m0s
[07:27:18.127] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:37:0)
[07:27:18.127] Timer tick, asking Buildbucket for the build status
[07:27:18.531] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:18.531] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:38:0) after 1m0s
[07:28:18.604] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:38:0)
[07:28:18.604] Timer tick, asking Buildbucket for the build status
[07:28:18.680] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:18.680] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:39:0) after 1m0s
[07:29:18.742] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:39:0)
[07:29:18.742] Timer tick, asking Buildbucket for the build status
[07:29:18.868] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:18.868] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:40:0) after 1m0s
[07:30:19.040] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:40:0)
[07:30:19.040] Timer tick, asking Buildbucket for the build status
[07:30:19.121] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:19.121] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:41:0) after 1m0s
[07:31:19.129] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:41:0)
[07:31:19.129] Timer tick, asking Buildbucket for the build status
[07:31:19.396] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:19.396] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:42:0) after 1m0s
[07:32:19.478] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:42:0)
[07:32:19.478] Timer tick, asking Buildbucket for the build status
[07:32:20.111] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:20.111] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:43:0) after 1m0s
[07:33:20.249] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:43:0)
[07:33:20.249] Timer tick, asking Buildbucket for the build status
[07:33:20.320] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:20.320] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:44:0) after 1m0s
[07:34:20.313] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:44:0)
[07:34:20.313] Timer tick, asking Buildbucket for the build status
[07:34:20.425] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:20.425] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:45:0) after 1m0s
[07:35:20.929] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:45:0)
[07:35:20.929] Timer tick, asking Buildbucket for the build status
[07:35:21.018] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:21.018] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:46:0) after 1m0s
[07:36:21.064] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:46:0)
[07:36:21.064] Timer tick, asking Buildbucket for the build status
[07:36:21.344] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:21.344] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:47:0) after 1m0s
[07:37:21.377] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:47:0)
[07:37:21.377] Timer tick, asking Buildbucket for the build status
[07:37:21.527] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:21.527] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:48:0) after 1m0s
[07:38:21.567] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:48:0)
[07:38:21.567] Timer tick, asking Buildbucket for the build status
[07:38:21.657] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:21.657] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:49:0) after 1m0s
[07:39:21.677] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:49:0)
[07:39:21.677] Timer tick, asking Buildbucket for the build status
[07:39:21.808] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:21.808] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:50:0) after 1m0s
[07:40:21.863] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:50:0)
[07:40:21.863] Timer tick, asking Buildbucket for the build status
[07:40:22.221] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:22.221] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:51:0) after 1m0s
[07:41:22.396] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:51:0)
[07:41:22.396] Timer tick, asking Buildbucket for the build status
[07:41:22.575] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:22.575] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:52:0) after 1m0s
[07:42:22.695] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:52:0)
[07:42:22.695] Timer tick, asking Buildbucket for the build status
[07:42:22.863] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:22.863] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:53:0) after 1m0s
[07:43:22.978] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:53:0)
[07:43:22.978] Timer tick, asking Buildbucket for the build status
[07:43:23.055] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:23.055] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:54:0) after 1m0s
[07:44:23.142] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:54:0)
[07:44:23.142] Timer tick, asking Buildbucket for the build status
[07:44:23.221] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:23.221] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:55:0) after 1m0s
[07:45:23.292] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:55:0)
[07:45:23.292] Timer tick, asking Buildbucket for the build status
[07:45:23.381] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:23.381] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:56:0) after 1m0s
[07:46:23.511] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:56:0)
[07:46:23.511] Timer tick, asking Buildbucket for the build status
[07:46:23.930] Build 8852444730022631632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:23.930] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9017874792013287056:57:0) after 1m0s
[07:46:45.260] Received PubSub notification, asking Buildbucket for the build status
[07:46:45.338] Build 8852444730022631632: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[07:46:45.338] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1616053604756897",
"created_by": "project:chromium",
"created_ts": "1616047845779604",
"failure_reason": "BUILD_FAILURE",
"id": "8852444730022631632",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0ab1b0e5191a2eadf6ad581f808c523104076ede\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 Test Suite(s) failed.\\n\\n**browser_tests** failed because of:\\n\\n- MediaAccessWebAppsTest.RemoveApp\\n\\n- SafeBrowsingBlockingPageBrowserTestWithThreatTypeAndIsolationSetting/SafeBrowsingBlockingPageBrowserTest.NavigatingBackAndForth/0\\n\\n- Providers/ExtendedFilesAppBrowserTest.Test/requestMountSourceDevice_DisableNativeSmb\\n\\n- DirectoryTreeContextMenu/FilesAppBrowserTest.Test/dirRenameToExisting_GuestMode\\n\\n- ...567 more failure(s) (571 total)...\"}, \"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\": \"0ab1b0e5191a2eadf6ad581f808c523104076ede\", \"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-123-w0ri\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 23031, \"got_angle_revision\": \"bb7c1442587acf6bdee94f62f18d85e0485c3ab7\", \"got_dawn_revision\": \"3209df5a9cc2de5415688653d1142b1adda554db\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"0ab1b0e5191a2eadf6ad581f808c523104076ede\", \"got_revision_cp\": \"refs/heads/master@{#864129}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a98fb3f8380a3a5c9ae3e0175634ba88c85a434f\", \"got_v8_revision\": \"4f0f4e2a3c5ad9dc7312774da5197e0179c30863\", \"got_v8_revision_cp\": \"refs/heads/9.1.146@{#1}\", \"got_webrtc_revision\": \"92a768ad66f54ca57002f6f2e87c7a732e3fec39\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33488}\", \"is_cached\": true, \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-dbg\", \"tryserver.chromium.chromiumos:linux-chromeos-compile-dbg\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0ab1b0e5191a2eadf6ad581f808c523104076ede\", \"swarm_hashes_refs/heads/master(at){#864129}_without_patch\": {\"absl_hardening_tests\": \"601cdf077f5a5742afe4a5b34b8c9e20cc0993ba\", \"accessibility_unittests\": \"c8e4634b2eced518bdff58d7175e0c7e6a5c339e\", \"angle_unittests\": \"d96c4f2307fbbb462dd28ccea934788ace438a7a\", \"app_list_unittests\": \"0f5efae44843dddeeb20b42b19602fb3129eff95\", \"app_shell_unittests\": \"829582be2320b85cd269a92bd85380d99e4b8dd4\", \"ash_unittests\": \"f54ff99d18262e23b98583bd165d3b10dd0158ec\", \"aura_unittests\": \"2f9a51ed59340cb4618328534c158839bb832314\", \"base_unittests\": \"66261eb67906b7a03110bf7ca47008782dd22569\", \"base_util_unittests\": \"14764c672d98bad3d8efc05a7ff009079fa1a98f\", \"blink_common_unittests\": \"16ea3cbc1bcf0814cb3e9af1be9dd257e9321273\", \"blink_fuzzer_unittests\": \"7a94552aa1adc69c913fb6e954dbf6bc3772be6f\", \"blink_heap_unittests\": \"122fa9b27c6a1a943b9bdcdd87ceac291c3ab5d0\", \"blink_platform_unittests\": \"f29148e57db6566a5e8babd2614ce2a9233a1350\", \"blink_unittests\": \"27e28801054c9df567b91872e5e0330259f7439a\", \"boringssl_crypto_tests\": \"70e68aad87a48e01a6ee6e07910576700561401e\", \"boringssl_ssl_tests\": \"bbf24598a2616752cc580c1f1f72e3917c3c51e6\", \"browser_tests\": \"724274e1aa37ac179f307c6dce83b5b8ef581732\", \"capture_unittests\": \"4f135e6791fe6d4a88da0465f2678d30f8267672\", \"cast_unittests\": \"d3a028499afe9e161129824bceccd4cd150982ba\", \"cc_unittests\": \"10019d53d667b1cd1be427ceab2418125dbfe20a\", \"chrome_app_unittests\": \"5d0bf5930f129599d9c7e85fd4c8aeea803fc454\", \"chromedriver_unittests\": \"a980eec08bbc3f32b911fe9c8c33cc659016bef5\", \"chromeos_components_unittests\": \"6bf34b82fda277a01920ed9ab659f4173deaad35\", \"chromeos_unittests\": \"1e94955e8f5e5910f6301632da7ca7fb492f5b00\", \"color_unittests\": \"7cc513ecc3e0d1c85034c230ea066e2333ed0352\", \"components_browsertests\": \"22559a13e80bb8ed766e698d36b01afe427c8762\", \"components_unittests\": \"4e7bb99cb0b70fa9412ff933b8f4ae6ab47c70c5\", \"compositor_unittests\": \"0eb2ead200aa71dcecc7b285e3c34660af3e6357\", \"content_browsertests\": \"62b155246e2660008c4b105ff39fc58a723da56d\", \"content_unittests\": \"c94ae44adc5ce55789b46fccb724e3c4d3e63b98\", \"crashpad_tests\": \"e09dfdc8aca8d7c7b0c53438ae1cc938f875833f\", \"crypto_unittests\": \"308721ee812679c466a6c349c9e3b0a8e77727e4\", \"dbus_unittests\": \"6f870b1098a1ed1289097b042adc8ea40f011c22\", \"device_unittests\": \"edcfb7ba84329489e3fb67f3692f0f8b7ad67c5c\", \"display_unittests\": \"9d79b83f7716035b6df84045f71c117caccae017\", \"events_unittests\": \"911dc5d008d4cfeec38af0531bf77ef391a67103\", \"exo_unittests\": \"787f84c33bbf88ba3fec4c670ea1eb00283b44c5\", \"extensions_browsertests\": \"139c142c0364407fb18e8abcad8b3dc2e7149879\", \"extensions_unittests\": \"f4590d8d6f5db5a0e8ccc0e23cda349697da13af\", \"filesystem_service_unittests\": \"18f68c458b5a65abf6378cb4d02a1cdee0416ebc\", \"gcm_unit_tests\": \"85428044eaa4d7350e0970282c527e04b0ce8e0a\", \"gfx_unittests\": \"76afd92cd4b2e3c3a7c1bfd068eeb97f6dcd5a55\", \"gin_unittests\": \"73ee0b4514041424788d8a797fdf0d543baf79d4\", \"gl_unittests_ozone\": \"b06ab91bbe80aa37e98ac4d8c2fb63058572bd25\", \"google_apis_unittests\": \"90cc0fa6dfa37d9e3627291b9e6ad7ac2f971150\", \"gpu_unittests\": \"a9da8326bd20ab91ca62784555a7c03e974628a0\", \"gwp_asan_unittests\": \"0244b82c1b6d00d44b29ca65f10c8cc14d3a0912\", \"interactive_ui_tests\": \"a2d77459540afdb67b868c4311190787cf5ba08a\", \"ipc_tests\": \"8cb21a5ca61a3334cd504a7c7415f63093156c4c\", \"jingle_unittests\": \"51196267b9ce57ef2c4ffabb80dae13c216915ec\", \"keyboard_unittests\": \"8a8a399191e4fa29df94a61962fa6ae2782fdeb3\", \"latency_unittests\": \"2b1d2b3692d9eed1addcebe7fd5b5f58d9c1f2df\", \"libjingle_xmpp_unittests\": \"005841ce3ddfc6dc041e11a14f3efa151baa059e\", \"liburlpattern_unittests\": \"2e955ad472c92253ab73c6783ad52f7d51fd0590\", \"media_blink_unittests\": \"3517a61a5852dd5e8fcf145d978248381fa793bc\", \"media_unittests\": \"508135efe497c6aed1a051e502802f4eafd3e9f3\", \"message_center_unittests\": \"528286027c7f1b8c581c5c3d6fa34b9c18399b0c\", \"midi_unittests\": \"d3856c03ab6d9a7d4111ebdd7a5f6eb04a98861a\", \"mojo_core_unittests\": \"48612218ec921c65d0887e27ac4e43a913bd6ed5\", \"mojo_unittests\": \"13bbc5b83e48007828097db7b1b5f93af803bfbe\", \"nacl_helper_nonsfi_unittests\": \"0a86e358d598d02d22873aaaaf9d213e6edcd5f3\", \"nacl_loader_unittests\": \"9a97b8cf02da13989a9e77665180d6786cdf0e92\", \"native_theme_unittests\": \"e1b1354a4493acfa4e56e56fc271f913eaa9a911\", \"net_unittests\": \"6d144a4546c5be515600bb15fd28cccd3629f470\", \"ozone_gl_unittests\": \"a54b319b38bdde5ed5aa708443a365354d2c1331\", \"ozone_unittests\": \"58905181f8ed2e55ba273968d716d2a122f1fa75\", \"ozone_x11_unittests\": \"308eb1030f21d437b98777662c7e41367b77e911\", \"pdf_unittests\": \"48ca19fc7f1f745aa9f354cf221ef440faa39759\", \"perfetto_unittests\": \"2df4844c9fabf9e5ea79e249829266bf4acd373a\", \"ppapi_unittests\": \"eab7dc28d9c40b71a6ef30d0db8fd78879e0cc8b\", \"printing_unittests\": \"0134961c255c0089694b49014d094fbcd07f1467\", \"remoting_unittests\": \"d00b00de521351c5c7d89234a1937a28b0aa9b4f\", \"sandbox_linux_unittests\": \"34bf3febadb2abc847adb9b731cd9222ed096186\", \"service_manager_unittests\": \"17e773329d093d7c519f6a999690f6f043efa922\", \"services_unittests\": \"9c1a2c0fb02003640bac6449cbb6c799da2f29cf\", \"shell_dialogs_unittests\": \"38fa92dce9b84ea848d528bd2efe76290ec911db\", \"shell_encryption_unittests\": \"03d4e3456cb40cb560e19ffa331586e14083e8a9\", \"skia_unittests\": \"024ade61ff76b27326efdb9582df04735816b05c\", \"snapshot_unittests\": \"9422d7ca2ac489ec6201420e7417e605b9bcd3d6\", \"sql_unittests\": \"ba6971fea86195f974287da0026131cea3831c7b\", \"storage_unittests\": \"a501f98fa695a85f406613c9cb232ecc455a0fea\", \"sync_integration_tests\": \"9d56a8b19efe8d7b69fd74ad445e7b92322c37e2\", \"traffic_annotation_auditor_unittests\": \"c354dd36abc94eeaf599daa96276d214647e691b\", \"ui_base_unittests\": \"d1c627ad575fd42a95c6e9e5d85162e1d94ee625\", \"ui_chromeos_unittests\": \"43508a6742982ffa84d86731fb490a911362d931\", \"ui_touch_selection_unittests\": \"969d1aee3b835dc1e8bc9dd8cd3ece69290451ec\", \"unit_tests\": \"60f813dc778ab719ceafefe29674f2d8e2499945\", \"url_unittests\": \"9a27ef7abe8fe5c4be39145e5026ecef09e69897\", \"usage_time_limit_unittests\": \"e5cd025d0604d65fa5c3b4c0459e1c5b6b94007c\", \"views_unittests\": \"6df0bee6d6a8816011a381d40289d9dd9bd4ce44\", \"viz_unittests\": \"dfe00cb1a90eca8ccea5282a22a070810f637c02\", \"wayland_client_tests\": \"d3cc929118390bbcbf2e0fef72cb3dcdb6465bb7\", \"wm_unittests\": \"082c47264717101752076a75f31f978d22596d91\", \"wtf_unittests\": \"acf224e95959872cbc04fd1494a0699393bc4a8c\", \"zlib_unittests\": \"591447ce822ffd64b02c814bf460c61402b367c8\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_12fe258969bd458c0c491cc8ec8a110de21b19ba6d4540c003334ed929b828ba_v2\", \"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"builder_5165e2de7796d7dcb4f0b4d7ef826fa605d2b1188c14fc8becf427ca962c0c8e_v2\", \"builder_7d3316d3e62555e2d69f10e77e29eb3429b9b1f483dcbd92e41f25657b896dff_v2\", \"builder_894514932588084ddd0ea5f4513645e1ddb80ffaa59b1cc6ad9f386fcd930b0e_v2\", \"builder_994c6d057c4d72e6e60d1eac48ee38aafacc9bb85f78c7911d66463be69b94de_v2\", \"builder_a8228d45a2e4046ab48e21b574ab916f6d3c7e4ad955b91040a9e9374d3dd18a_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-123-w0ri\"], \"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\", \"2.7\", \"2.7.12\"], \"server_version\": [\"5776-cb9e157\"], \"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- MediaAccessWebAppsTest.RemoveApp\\n\\n- SafeBrowsingBlockingPageBrowserTestWithThreatTypeAndIsolationSetting/SafeBrowsingBlockingPageBrowserTest.NavigatingBackAndForth/0\\n\\n- Providers/ExtendedFilesAppBrowserTest.Test/requestMountSourceDevice_DisableNativeSmb\\n\\n- DirectoryTreeContextMenu/FilesAppBrowserTest.Test/dirRenameToExisting_GuestMode\\n\\n- ...567 more failure(s) (571 total)...\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1616047848482960",
"status": "COMPLETED",
"status_changed_ts": "1616047848748375",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/23031",
"builder:linux-chromeos-dbg",
"buildset:commit/git/0ab1b0e5191a2eadf6ad581f808c523104076ede",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0ab1b0e5191a2eadf6ad581f808c523104076ede",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9017874792013287056",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8852444730022631632/+/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:525cc6bc54bb6a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1616053604757646",
"url": "https://ci.chromium.org/b/8852444730022631632",
"utcnow_ts": "1616053605328660"
}
}
[07:46:45.338] Invocation finished in 1h36m0.84130002s with status FAILED