[16:43:18.356] New invocation is queued and will start shortly
[16:43:19.553] Starting the invocation (attempt 1)
[16:43:19.646] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:43:19.646] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:43:19.646] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9059781389987111104",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"bc0f48947afcf47177bbd79c1e93601012b7d21f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9059781389987111104",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/bc0f48947afcf47177bbd79c1e93601012b7d21f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bc0f48947afcf47177bbd79c1e93601012b7d21f",
"gitiles_ref:refs/heads/master"
]
}
[16:43:20.790] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1576082599853824",
"id": "8894351327734427728",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bc0f48947afcf47177bbd79c1e93601012b7d21f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1576082600415550",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/165958",
"builder:Linux Builder (dbg)",
"buildset:commit/git/bc0f48947afcf47177bbd79c1e93601012b7d21f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bc0f48947afcf47177bbd79c1e93601012b7d21f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059781389987111104",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8894351327734427728/+/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": "1576082600415648",
"url": "https://ci.chromium.org/b/8894351327734427728",
"utcnow_ts": "1576082600774113"
}
}
[16:43:20.790] Task URL: https://ci.chromium.org/b/8894351327734427728
[16:43:20.790] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:2:0) after 1m0s
[16:44:20.930] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:2:0)
[16:44:20.930] Timer tick, asking Buildbucket for the build status
[16:44:21.160] Build 8894351327734427728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:44:21.160] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:3:0) after 1m0s
[16:45:21.440] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:3:0)
[16:45:21.440] Timer tick, asking Buildbucket for the build status
[16:45:22.048] Build 8894351327734427728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:45:22.048] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:4:0) after 1m0s
[16:46:22.303] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:4:0)
[16:46:22.303] Timer tick, asking Buildbucket for the build status
[16:46:23.275] Build 8894351327734427728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:46:23.275] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:5:0) after 1m0s
[16:47:23.526] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:5:0)
[16:47:23.526] Timer tick, asking Buildbucket for the build status
[16:47:24.141] Build 8894351327734427728: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:47:24.141] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:6:0) after 1m0s
[16:48:06.898] Received PubSub notification, asking Buildbucket for the build status
[16:48:07.075] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:24.467] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:6:0)
[16:48:24.467] Timer tick, asking Buildbucket for the build status
[16:48:24.976] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:24.976] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:8:0) after 1m0s
[16:49:25.216] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:8:0)
[16:49:25.216] Timer tick, asking Buildbucket for the build status
[16:49:25.490] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:25.490] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:9:0) after 1m0s
[16:50:25.684] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:9:0)
[16:50:25.684] Timer tick, asking Buildbucket for the build status
[16:50:25.977] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:25.977] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:10:0) after 1m0s
[16:51:26.213] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:10:0)
[16:51:26.213] Timer tick, asking Buildbucket for the build status
[16:51:26.855] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:26.855] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:11:0) after 1m0s
[16:52:27.008] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:11:0)
[16:52:27.008] Timer tick, asking Buildbucket for the build status
[16:52:27.567] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:27.567] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:12:0) after 1m0s
[16:53:28.008] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:12:0)
[16:53:28.008] Timer tick, asking Buildbucket for the build status
[16:53:28.366] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:28.366] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:13:0) after 1m0s
[16:54:28.396] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:13:0)
[16:54:28.396] Timer tick, asking Buildbucket for the build status
[16:54:28.622] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:28.622] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:14:0) after 1m0s
[16:55:28.863] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:14:0)
[16:55:28.863] Timer tick, asking Buildbucket for the build status
[16:55:30.089] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:30.089] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:15:0) after 1m0s
[16:56:30.152] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:15:0)
[16:56:30.152] Timer tick, asking Buildbucket for the build status
[16:56:33.367] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:33.367] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:16:0) after 1m0s
[16:57:33.569] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:16:0)
[16:57:33.569] Timer tick, asking Buildbucket for the build status
[16:57:34.300] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:34.300] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:17:0) after 1m0s
[16:58:34.356] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:17:0)
[16:58:34.356] Timer tick, asking Buildbucket for the build status
[16:58:36.479] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:36.479] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:18:0) after 1m0s
[16:59:36.607] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:18:0)
[16:59:36.607] Timer tick, asking Buildbucket for the build status
[16:59:37.586] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:37.586] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:19:0) after 1m0s
[17:00:37.702] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:19:0)
[17:00:37.702] Timer tick, asking Buildbucket for the build status
[17:00:38.354] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:38.354] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:20:0) after 1m0s
[17:01:38.465] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:20:0)
[17:01:38.465] Timer tick, asking Buildbucket for the build status
[17:01:38.688] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:38.688] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:21:0) after 1m0s
[17:02:38.945] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:21:0)
[17:02:38.945] Timer tick, asking Buildbucket for the build status
[17:02:39.300] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:39.300] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:22:0) after 1m0s
[17:03:39.520] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:22:0)
[17:03:39.520] Timer tick, asking Buildbucket for the build status
[17:03:40.483] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:40.483] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:23:0) after 1m0s
[17:04:40.627] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:23:0)
[17:04:40.627] Timer tick, asking Buildbucket for the build status
[17:04:41.176] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:41.176] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:24:0) after 1m0s
[17:05:41.200] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:24:0)
[17:05:41.201] Timer tick, asking Buildbucket for the build status
[17:05:41.453] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:41.453] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:25:0) after 1m0s
[17:06:41.524] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:25:0)
[17:06:41.524] Timer tick, asking Buildbucket for the build status
[17:06:41.703] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:41.703] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:26:0) after 1m0s
[17:07:41.727] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:26:0)
[17:07:41.727] Timer tick, asking Buildbucket for the build status
[17:07:42.119] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:42.119] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:27:0) after 1m0s
[17:08:42.423] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:27:0)
[17:08:42.423] Timer tick, asking Buildbucket for the build status
[17:08:42.777] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:42.777] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:28:0) after 1m0s
[17:09:42.942] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:28:0)
[17:09:42.943] Timer tick, asking Buildbucket for the build status
[17:09:43.253] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:43.253] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:29:0) after 1m0s
[17:10:43.311] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:29:0)
[17:10:43.311] Timer tick, asking Buildbucket for the build status
[17:10:43.717] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:43.717] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:30:0) after 1m0s
[17:11:43.886] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:30:0)
[17:11:43.886] Timer tick, asking Buildbucket for the build status
[17:11:44.239] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:44.239] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:31:0) after 1m0s
[17:12:44.292] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:31:0)
[17:12:44.292] Timer tick, asking Buildbucket for the build status
[17:12:44.600] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:44.600] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:32:0) after 1m0s
[17:13:44.719] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:32:0)
[17:13:44.719] Timer tick, asking Buildbucket for the build status
[17:13:45.940] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:45.940] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:33:0) after 1m0s
[17:14:45.974] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:33:0)
[17:14:45.974] Timer tick, asking Buildbucket for the build status
[17:14:46.225] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:46.225] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:34:0) after 1m0s
[17:15:46.417] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:34:0)
[17:15:46.417] Timer tick, asking Buildbucket for the build status
[17:15:47.078] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:47.078] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:35:0) after 1m0s
[17:16:47.204] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:35:0)
[17:16:47.204] Timer tick, asking Buildbucket for the build status
[17:16:48.607] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:48.607] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:36:0) after 1m0s
[17:17:48.668] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:36:0)
[17:17:48.668] Timer tick, asking Buildbucket for the build status
[17:17:49.139] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:49.139] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:37:0) after 1m0s
[17:18:49.297] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:37:0)
[17:18:49.297] Timer tick, asking Buildbucket for the build status
[17:18:49.817] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:49.817] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:38:0) after 1m0s
[17:19:49.927] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:38:0)
[17:19:49.927] Timer tick, asking Buildbucket for the build status
[17:19:51.367] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:51.367] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:39:0) after 1m0s
[17:20:51.653] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:39:0)
[17:20:51.653] Timer tick, asking Buildbucket for the build status
[17:20:52.108] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:52.108] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:40:0) after 1m0s
[17:21:52.469] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:40:0)
[17:21:52.469] Timer tick, asking Buildbucket for the build status
[17:21:53.621] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:53.621] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:41:0) after 1m0s
[17:22:53.807] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:41:0)
[17:22:53.807] Timer tick, asking Buildbucket for the build status
[17:22:54.641] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:54.641] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:42:0) after 1m0s
[17:23:54.953] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:42:0)
[17:23:54.953] Timer tick, asking Buildbucket for the build status
[17:23:55.715] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:55.715] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:43:0) after 1m0s
[17:24:55.902] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:43:0)
[17:24:55.902] Timer tick, asking Buildbucket for the build status
[17:24:58.125] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:58.125] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:44:0) after 1m0s
[17:25:58.335] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:44:0)
[17:25:58.336] Timer tick, asking Buildbucket for the build status
[17:25:58.792] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:58.792] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:45:0) after 1m0s
[17:26:58.841] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:45:0)
[17:26:58.841] Timer tick, asking Buildbucket for the build status
[17:26:59.034] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:59.035] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:46:0) after 1m0s
[17:27:59.187] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:46:0)
[17:27:59.187] Timer tick, asking Buildbucket for the build status
[17:27:59.349] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:59.349] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:47:0) after 1m0s
[17:28:59.657] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:47:0)
[17:28:59.657] Timer tick, asking Buildbucket for the build status
[17:28:59.871] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:59.871] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:48:0) after 1m0s
[17:30:00.185] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:48:0)
[17:30:00.185] Timer tick, asking Buildbucket for the build status
[17:30:00.678] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:00.678] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:49:0) after 1m0s
[17:31:00.942] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:49:0)
[17:31:00.942] Timer tick, asking Buildbucket for the build status
[17:31:01.379] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:01.381] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:50:0) after 1m0s
[17:32:01.601] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:50:0)
[17:32:01.601] Timer tick, asking Buildbucket for the build status
[17:32:02.193] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:02.193] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:51:0) after 1m0s
[17:33:02.323] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:51:0)
[17:33:02.323] Timer tick, asking Buildbucket for the build status
[17:33:02.525] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:02.525] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:52:0) after 1m0s
[17:34:02.736] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:52:0)
[17:34:02.736] Timer tick, asking Buildbucket for the build status
[17:34:03.013] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:03.013] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:53:0) after 1m0s
[17:35:03.286] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:53:0)
[17:35:03.286] Timer tick, asking Buildbucket for the build status
[17:35:05.150] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:05.150] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:54:0) after 1m0s
[17:36:05.231] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:54:0)
[17:36:05.231] Timer tick, asking Buildbucket for the build status
[17:36:11.353] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:11.353] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:55:0) after 1m0s
[17:37:11.480] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:55:0)
[17:37:11.480] Timer tick, asking Buildbucket for the build status
[17:37:11.974] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:11.974] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:56:0) after 1m0s
[17:38:12.128] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:56:0)
[17:38:12.128] Timer tick, asking Buildbucket for the build status
[17:38:12.919] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:12.919] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:57:0) after 1m0s
[17:39:13.005] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:57:0)
[17:39:13.005] Timer tick, asking Buildbucket for the build status
[17:39:14.323] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:14.323] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:58:0) after 1m0s
[17:40:14.419] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:58:0)
[17:40:14.419] Timer tick, asking Buildbucket for the build status
[17:40:14.758] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:14.758] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:59:0) after 1m0s
[17:41:14.915] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:59:0)
[17:41:14.915] Timer tick, asking Buildbucket for the build status
[17:41:17.936] Build 8894351327734427728: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:17.936] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059781389987111104:60:0) after 1m0s
[17:42:00.046] Received PubSub notification, asking Buildbucket for the build status
[17:42:00.531] Build 8894351327734427728: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:42:00.532] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1576086119404120",
"created_by": "project:chromium",
"created_ts": "1576082599853824",
"id": "8894351327734427728",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bc0f48947afcf47177bbd79c1e93601012b7d21f\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$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-21-3ers\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 165958, \"got_angle_revision\": \"1ed23f2d9dcaf9672104ff20d798413cab1d9891\", \"got_dawn_revision\": \"e8e9d5daf59fcf19f1a2e1b36e8da7437e0264f5\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"bc0f48947afcf47177bbd79c1e93601012b7d21f\", \"got_revision_cp\": \"refs/heads/master@{#723849}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"5c9a33825dc1924abf8677fcb71bf07e229a96a5\", \"got_v8_revision\": \"87599d7029ad6d7b44af9e8a82cc737128d7713c\", \"got_v8_revision_cp\": \"refs/heads/8.1.15@{#1}\", \"got_webrtc_revision\": \"3fdb3cbc6aeed9d2598f076a6740374c11d4c06f\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30059}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"bc0f48947afcf47177bbd79c1e93601012b7d21f\", \"swarm_hashes_refs/heads/master(at){#723849}_without_patch\": {\"accessibility_unittests\": \"756f7144d9c988eb346e1b78f579e3ebb62f0ed4\", \"angle_unittests\": \"89715ca47d2692a2a94c8dc6e4e88c976797f7ab\", \"app_shell_unittests\": \"7c2b26f705c3886fbb0e77dfb76cf69a56e1543d\", \"aura_unittests\": \"f3e7ccab7e9a4d9a74008f25d72641b8b1129ce3\", \"base_unittests\": \"3529938ae433b17ab961109295ffe3059eb555c2\", \"base_util_unittests\": \"485c91b0a97c1d80af2d66165bb2223657b077f3\", \"blink_common_unittests\": \"e61c7a405ea04761f98225a63f6e193aa8d066e9\", \"blink_fuzzer_unittests\": \"04e7c68bf8e3bf2432c81af97338cf66752fce81\", \"blink_heap_unittests\": \"064e88173b3a7ceb5744999470a14ea7ab0b211e\", \"blink_platform_unittests\": \"fde4380110a3bee5d52f75da2da599a79900b6c3\", \"blink_python_tests\": \"aad7ba171f736a62d497ccaf3d07946886777435\", \"blink_unittests\": \"eab78fa2269ba46e4b461afe4d8a07e69d38c1bb\", \"blink_web_tests\": \"65a864a21105cfd7d013642c1d12d9d4755650e5\", \"boringssl_crypto_tests\": \"11f1a077fa0155406b0c8d3bef04b839391a6e2d\", \"boringssl_ssl_tests\": \"3da0a7fbee1468544df83d41017ded0ac30bc959\", \"browser_tests\": \"eb56d6e339d98857c89fdfa5ab8332d39e75e8f6\", \"cacheinvalidation_unittests\": \"c6155d973b1ded198a26e03827ebb7c8c38128d9\", \"capture_unittests\": \"a2364aa1ecb5400984fd26b9f0515d33c7f327d5\", \"cast_unittests\": \"251f2ce36e614185cf749710057af64498e537da\", \"cc_unittests\": \"37b1d3c5a85013b21092224700dd5212bad096ac\", \"chrome_app_unittests\": \"33125e87867f5affafed4435b1ed6ea0af52c00f\", \"chromedriver_unittests\": \"67009cecb6da10c0ecba549273481aa363244b44\", \"components_browsertests\": \"41527bdfe3c81a21b7c12b2a8a7964549b7a7b07\", \"components_unittests\": \"fc11b36f59971a780b2a87b3603e4e2447252343\", \"compositor_unittests\": \"ccc465903fc27859354ed15b275ee2107d7fba8d\", \"content_browsertests\": \"2276c161c149dace7d9870787674f47c300eb0b8\", \"content_shell_crash_test\": \"cc1908a313ec821e38e93caa25a77fcb7fd07910\", \"content_unittests\": \"004f7d25ef1c9190231a255524065fefecfa4ac7\", \"crashpad_tests\": \"a0bd57225e265c9c255f49c6a9577d071b8397ed\", \"cronet_tests\": \"31ece7d3c50c67a650a1f17eca3318339aaaa339\", \"cronet_unittests\": \"0e4327e626e185242ba219951a04333abd6ef7f1\", \"crypto_unittests\": \"d80790968ca92932e10de78677ade7089acad773\", \"dbus_unittests\": \"6a2855af41cd183e5d170fcd65e88bae8a26dede\", \"device_unittests\": \"38df9463ba9fa8cf74c0cbb2c8e3e9933c044ca8\", \"devtools_lint_check\": \"17afeea451e503777274f62cf843cc7513e53c4f\", \"devtools_type_check\": \"76e687f6a547cde49dee1f0ff61039e8d30f1e70\", \"display_unittests\": \"0a3c891e3fcea6060c3aa694f7ff410966538383\", \"events_unittests\": \"6421ec56b842df3104600f89d80e16e543d5bcd2\", \"extensions_browsertests\": \"9701b92044a580ada08133f55e488892cb7989cd\", \"extensions_unittests\": \"3feb30d3c5ba2816b57f321c1caf53998b72f6a2\", \"filesystem_service_unittests\": \"08abc8f3a5ab91412d49d490d51b53d4bee71c4d\", \"flatbuffers_unittests\": \"034d1c63a8199a680c9bbf29b5866b1c851e28ee\", \"gcm_unit_tests\": \"8c71536ad54968b4eeb21ecc120a6d60a81d32b4\", \"gfx_unittests\": \"2411939403f525bc8c5af613a76e35f57629b361\", \"gin_unittests\": \"a72d63da1c02c97371190bbd3c2049319e18d1fd\", \"google_apis_unittests\": \"d77921ceb5fbcdc7dd1d1100f738b5818c00ae6a\", \"gpu_unittests\": \"68a7eb382fe0ab85043814f1b58683ac67d1e902\", \"grit_python_unittests\": \"c949091fb2717b913781a9a7cb15efe1537c4608\", \"gwp_asan_unittests\": \"b2939cd5fd508c3da068939b0cdf718613826a50\", \"headless_browsertests\": \"d12029f2eee4ac56dac28fdf949e9987558a1649\", \"headless_unittests\": \"500b9d40b7071d5b635675c3f2a8851731a3ffc7\", \"interactive_ui_tests\": \"462c00a0070b46ca5f96be1b6b29e137d637890b\", \"ipc_tests\": \"7dfbc62764178e3a7846ee7a0de24d3b06b3a4e1\", \"jingle_unittests\": \"e9f75647273ab41b453ffde1a40b2457980f3440\", \"latency_unittests\": \"5f0349e4f71e216e3ef206939b952b040bbf0d16\", \"libjingle_xmpp_unittests\": \"387250ed37fbd5a449bae27c06bf234737f70585\", \"media_blink_unittests\": \"f0272a8f0c284c89ed9adc5dbe32cd0c029a297b\", \"media_service_unittests\": \"9ecf1b6bc7a2cae64410f0b724cc43525eb520f6\", \"media_unittests\": \"1a172cc7eb69290955407ae57d2b4bad2af877c3\", \"message_center_unittests\": \"5c707c4a517c8908db12db4865d9b0897bef051a\", \"metrics_python_tests\": \"36a76d31b4de9315a68b6db5e3c5f12837b89ecd\", \"midi_unittests\": \"656d8b45a0c954ffecc112a792f1078692cfa50b\", \"mojo_core_unittests\": \"7b73240ca727f3e980fb9795ce869c16fac4099d\", \"mojo_unittests\": \"7de6a643957e16954bb94e8b275d50f841d0e701\", \"nacl_helper_nonsfi_unittests\": \"c2d453b20530d77066a37ad40698718617fb458f\", \"nacl_loader_unittests\": \"7d2b2d491fe3d01ce6609ff4110f7ec666dea6bb\", \"native_theme_unittests\": \"94f6adca3d119ba7ac9a80e7d0ddd104b59f229e\", \"net_unittests\": \"7e0ffd0046e04a5fd7c79013efba7c86b1bdb02a\", \"pdf_unittests\": \"3103fd9ebf135e84e97bf28e45ea697472df17e4\", \"perfetto_unittests\": \"2469bcaf7a1e1bb170dcf2b30f3e4465f00a0a54\", \"ppapi_unittests\": \"e559fa59a400ba2948cb305897074d9e081dd892\", \"printing_unittests\": \"b654a40ee32ae9cdf0e346c816aebf18ffc9bb42\", \"remoting_unittests\": \"a4aad0799b8b5a3f7945b61dc1c71cea3a8018ba\", \"sandbox_linux_unittests\": \"0caa77dd588ea291810a44b1ca72eb99fc894e81\", \"service_manager_unittests\": \"3c4ce3938a5b60e734758b8dcb22c5dd5232a788\", \"services_unittests\": \"94cbec71f54581917354d0dabfbbd47135b5313d\", \"shell_dialogs_unittests\": \"aea70b257bddb721168ee1aa9e8c00954847f61c\", \"skia_unittests\": \"b23fe07d93fcbf1a2430bb7a717e12f44c53429e\", \"snapshot_unittests\": \"7f17e6f9c0a8bd55f04872f14b833e32c33fd7d1\", \"sql_unittests\": \"d52ef03a4d997eb7f9bbf468b9c2325083842dfc\", \"storage_unittests\": \"3c12c623efecab360751f7b2647547aeef1f0377\", \"sync_integration_tests\": \"1513782e8e45ffa0579466fe43f0f9c1800c0f97\", \"telemetry_gpu_unittests\": \"4534a4e5a1460b465215d921b48e2fe0cc285ddb\", \"telemetry_perf_unittests\": \"8c569f4cd881d28b58c8d9bcfe38e930b976264a\", \"telemetry_unittests\": \"fa949a67d872ef86c8d3b0bc53c4661c8140001b\", \"traffic_annotation_auditor_unittests\": \"f29250601fc3defbb0d199ff0f56ab40c9b60722\", \"ui_base_unittests\": \"2bab70f892ec03dc1ae2297260c41143713c659b\", \"ui_touch_selection_unittests\": \"22cf1180eac2641efb1c6f5c6e7b6da4eb253e7b\", \"unit_tests\": \"dc0aba5c2785fa63829474d9029423ff99e73748\", \"url_unittests\": \"a94d03792bc2852ff36cb1ac1f884dfa1540f907\", \"views_perftests\": \"87dfdfb46fce16f08ea00c8f94637e59ddb39dcd\", \"views_unittests\": \"6346f5dc8985c1fbd15b1390c3222694041ff2ef\", \"viz_unittests\": \"2d217f17769f5fb6ab699a5e4abd93c3720ab6bc\", \"vr_common_unittests\": \"b587018545d542ee9bf52632b8bf96623affba40\", \"vr_pixeltests\": \"17415bdbf1c18f904818545e651b0f7a43f2bcf0\", \"webdriver_wpt_tests\": \"9e14be8a1f04b056fdb6759445d1428b11c2e4b0\", \"weblayer_browsertests\": \"e7005fcccf4b50ea8a3f1c260825495f72a01031\", \"wm_unittests\": \"1aab0e2c7a75c9387952d2852591c6510781eea8\", \"wtf_unittests\": \"43019f5a04b08fe499e79b7a3810716593d054ba\", \"xr_browser_tests\": \"f90481d120ea4983713416bd322e527dbaf95d32\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1313787165392eb219c57f145b1b9e0fbbb62d544385caa60e27617303515107_v2\", \"builder_279625ee090c361ea8cf031374b7b020d1191c526e0ef1068ab634999c69ee70_v2\", \"builder_a2d0deb167035deb42f2d46ec8df60bd17682f54960fd7d9f79bcb4fddee3411_v2\", \"builder_f5529a61c4772e2a2b7c80424c65e6b688bd4755892e8f82a67b8d7980cd8e00_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-21-3ers\"], \"image\": [\"chrome-xenial-19120800-09d8782d9a1\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4709-f2c88b6\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1576082885736120",
"status": "COMPLETED",
"status_changed_ts": "1576086119740069",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/165958",
"builder:Linux Builder (dbg)",
"buildset:commit/git/bc0f48947afcf47177bbd79c1e93601012b7d21f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bc0f48947afcf47177bbd79c1e93601012b7d21f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059781389987111104",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8894351327734427728/+/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:490ea97c18821810",
"user_agent:luci-scheduler"
],
"updated_ts": "1576086119744384",
"url": "https://ci.chromium.org/b/8894351327734427728",
"utcnow_ts": "1576086120515439"
}
}
[17:42:00.532] Invocation finished in 58m42.210831279s with status SUCCEEDED