[18:23:00.875] New invocation is queued and will start shortly
[18:23:05.953] Starting the invocation (attempt 1)
[18:23:06.122] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:23:06.122] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:23:06.122] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9061224668317779552",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"02be7d981436eba2394cdf3fcd611f1eb253225a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9061224668317779552",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/02be7d981436eba2394cdf3fcd611f1eb253225a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/02be7d981436eba2394cdf3fcd611f1eb253225a",
"gitiles_ref:refs/heads/master"
]
}
[18:23:07.784] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1574706186445882",
"id": "8895794601800593344",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"02be7d981436eba2394cdf3fcd611f1eb253225a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1574706187033910",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/165273",
"builder:Linux Builder (dbg)",
"buildset:commit/git/02be7d981436eba2394cdf3fcd611f1eb253225a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/02be7d981436eba2394cdf3fcd611f1eb253225a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9061224668317779552",
"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/8895794601800593344/+/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": "1574706187034026",
"url": "https://ci.chromium.org/b/8895794601800593344",
"utcnow_ts": "1574706187690622"
}
}
[18:23:07.784] Task URL: https://ci.chromium.org/b/8895794601800593344
[18:23:07.784] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:2:0) after 1m0s
[18:24:07.924] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:2:0)
[18:24:07.924] Timer tick, asking Buildbucket for the build status
[18:24:08.104] Build 8895794601800593344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:24:08.104] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:3:0) after 1m0s
[18:25:08.293] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:3:0)
[18:25:08.293] Timer tick, asking Buildbucket for the build status
[18:25:08.621] Build 8895794601800593344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:25:08.621] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:4:0) after 1m0s
[18:26:08.841] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:4:0)
[18:26:08.841] Timer tick, asking Buildbucket for the build status
[18:26:09.040] Build 8895794601800593344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:26:09.040] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:5:0) after 1m0s
[18:27:09.246] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:5:0)
[18:27:09.246] Timer tick, asking Buildbucket for the build status
[18:27:09.403] Build 8895794601800593344: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:27:09.403] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:6:0) after 1m0s
[18:28:03.372] Received PubSub notification, asking Buildbucket for the build status
[18:28:03.507] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:09.425] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:6:0)
[18:28:09.425] Timer tick, asking Buildbucket for the build status
[18:28:09.705] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:09.705] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:8:0) after 1m0s
[18:29:09.823] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:8:0)
[18:29:09.823] Timer tick, asking Buildbucket for the build status
[18:29:10.003] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:10.003] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:9:0) after 1m0s
[18:30:10.124] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:9:0)
[18:30:10.124] Timer tick, asking Buildbucket for the build status
[18:30:10.773] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:10.773] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:10:0) after 1m0s
[18:31:10.803] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:10:0)
[18:31:10.803] Timer tick, asking Buildbucket for the build status
[18:31:10.930] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:10.930] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:11:0) after 1m0s
[18:32:11.002] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:11:0)
[18:32:11.002] Timer tick, asking Buildbucket for the build status
[18:32:11.595] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:11.595] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:12:0) after 1m0s
[18:33:11.615] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:12:0)
[18:33:11.615] Timer tick, asking Buildbucket for the build status
[18:33:11.844] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:11.844] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:13:0) after 1m0s
[18:34:12.024] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:13:0)
[18:34:12.024] Timer tick, asking Buildbucket for the build status
[18:34:12.229] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:12.229] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:14:0) after 1m0s
[18:35:12.347] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:14:0)
[18:35:12.347] Timer tick, asking Buildbucket for the build status
[18:35:12.497] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:12.497] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:15:0) after 1m0s
[18:36:12.761] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:15:0)
[18:36:12.761] Timer tick, asking Buildbucket for the build status
[18:36:13.168] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:13.168] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:16:0) after 1m0s
[18:37:13.294] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:16:0)
[18:37:13.294] Timer tick, asking Buildbucket for the build status
[18:37:13.704] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:13.705] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:17:0) after 1m0s
[18:38:13.695] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:17:0)
[18:38:13.695] Timer tick, asking Buildbucket for the build status
[18:38:15.932] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:15.932] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:18:0) after 1m0s
[18:39:16.223] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:18:0)
[18:39:16.223] Timer tick, asking Buildbucket for the build status
[18:39:16.343] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:16.343] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:19:0) after 1m0s
[18:40:16.497] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:19:0)
[18:40:16.497] Timer tick, asking Buildbucket for the build status
[18:40:17.037] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:17.037] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:20:0) after 1m0s
[18:41:17.146] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:20:0)
[18:41:17.146] Timer tick, asking Buildbucket for the build status
[18:41:18.780] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:18.780] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:21:0) after 1m0s
[18:42:19.004] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:21:0)
[18:42:19.004] Timer tick, asking Buildbucket for the build status
[18:42:20.305] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:20.305] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:22:0) after 1m0s
[18:43:20.407] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:22:0)
[18:43:20.407] Timer tick, asking Buildbucket for the build status
[18:43:20.514] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:20.514] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:23:0) after 1m0s
[18:44:20.636] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:23:0)
[18:44:20.636] Timer tick, asking Buildbucket for the build status
[18:44:21.124] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:21.124] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:24:0) after 1m0s
[18:45:21.270] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:24:0)
[18:45:21.270] Timer tick, asking Buildbucket for the build status
[18:45:24.756] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:24.756] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:25:0) after 1m0s
[18:46:24.946] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:25:0)
[18:46:24.946] Timer tick, asking Buildbucket for the build status
[18:46:25.900] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:25.900] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:26:0) after 1m0s
[18:47:25.932] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:26:0)
[18:47:25.932] Timer tick, asking Buildbucket for the build status
[18:47:26.214] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:26.214] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:27:0) after 1m0s
[18:48:26.823] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:27:0)
[18:48:26.823] Timer tick, asking Buildbucket for the build status
[18:48:27.095] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:27.095] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:28:0) after 1m0s
[18:49:27.268] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:28:0)
[18:49:27.268] Timer tick, asking Buildbucket for the build status
[18:49:27.625] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:27.625] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:29:0) after 1m0s
[18:50:27.928] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:29:0)
[18:50:27.928] Timer tick, asking Buildbucket for the build status
[18:50:28.070] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:28.070] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:30:0) after 1m0s
[18:51:28.410] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:30:0)
[18:51:28.410] Timer tick, asking Buildbucket for the build status
[18:51:28.619] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:28.619] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:31:0) after 1m0s
[18:52:28.727] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:31:0)
[18:52:28.727] Timer tick, asking Buildbucket for the build status
[18:52:29.119] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:29.119] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:32:0) after 1m0s
[18:53:29.209] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:32:0)
[18:53:29.209] Timer tick, asking Buildbucket for the build status
[18:53:29.472] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:29.472] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:33:0) after 1m0s
[18:54:29.559] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:33:0)
[18:54:29.559] Timer tick, asking Buildbucket for the build status
[18:54:29.683] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:29.683] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:34:0) after 1m0s
[18:55:29.835] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:34:0)
[18:55:29.835] Timer tick, asking Buildbucket for the build status
[18:55:30.044] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:30.044] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:35:0) after 1m0s
[18:56:30.146] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:35:0)
[18:56:30.147] Timer tick, asking Buildbucket for the build status
[18:56:30.586] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:30.586] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:36:0) after 1m0s
[18:57:30.847] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:36:0)
[18:57:30.847] Timer tick, asking Buildbucket for the build status
[18:57:31.127] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:31.127] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:37:0) after 1m0s
[18:58:31.314] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:37:0)
[18:58:31.314] Timer tick, asking Buildbucket for the build status
[18:58:31.825] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:31.825] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:38:0) after 1m0s
[18:59:31.972] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:38:0)
[18:59:31.972] Timer tick, asking Buildbucket for the build status
[18:59:32.179] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:32.179] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:39:0) after 1m0s
[19:00:32.262] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:39:0)
[19:00:32.262] Timer tick, asking Buildbucket for the build status
[19:00:32.484] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:32.484] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:40:0) after 1m0s
[19:01:32.576] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:40:0)
[19:01:32.576] Timer tick, asking Buildbucket for the build status
[19:01:32.730] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:32.730] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:41:0) after 1m0s
[19:02:32.782] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:41:0)
[19:02:32.782] Timer tick, asking Buildbucket for the build status
[19:02:33.195] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:33.195] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:42:0) after 1m0s
[19:03:33.261] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:42:0)
[19:03:33.261] Timer tick, asking Buildbucket for the build status
[19:03:33.663] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:33.663] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:43:0) after 1m0s
[19:04:33.736] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:43:0)
[19:04:33.736] Timer tick, asking Buildbucket for the build status
[19:04:33.889] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:33.889] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:44:0) after 1m0s
[19:05:33.917] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:44:0)
[19:05:33.917] Timer tick, asking Buildbucket for the build status
[19:05:34.357] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:34.357] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:45:0) after 1m0s
[19:06:34.402] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:45:0)
[19:06:34.402] Timer tick, asking Buildbucket for the build status
[19:06:34.889] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:34.890] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:46:0) after 1m0s
[19:07:34.948] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:46:0)
[19:07:34.948] Timer tick, asking Buildbucket for the build status
[19:07:35.272] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:35.272] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:47:0) after 1m0s
[19:08:35.313] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:47:0)
[19:08:35.313] Timer tick, asking Buildbucket for the build status
[19:08:36.289] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:36.289] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:48:0) after 1m0s
[19:09:36.385] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:48:0)
[19:09:36.385] Timer tick, asking Buildbucket for the build status
[19:09:36.825] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:36.825] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:49:0) after 1m0s
[19:10:37.045] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:49:0)
[19:10:37.045] Timer tick, asking Buildbucket for the build status
[19:10:39.327] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:39.327] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:50:0) after 1m0s
[19:11:39.424] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:50:0)
[19:11:39.424] Timer tick, asking Buildbucket for the build status
[19:11:39.915] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:39.915] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:51:0) after 1m0s
[19:12:39.909] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:51:0)
[19:12:39.909] Timer tick, asking Buildbucket for the build status
[19:12:41.108] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:41.108] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:52:0) after 1m0s
[19:13:41.196] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:52:0)
[19:13:41.196] Timer tick, asking Buildbucket for the build status
[19:13:41.740] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:41.740] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:53:0) after 1m0s
[19:14:41.840] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:53:0)
[19:14:41.840] Timer tick, asking Buildbucket for the build status
[19:14:42.012] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:42.012] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:54:0) after 1m0s
[19:15:42.074] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:54:0)
[19:15:42.074] Timer tick, asking Buildbucket for the build status
[19:15:42.808] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:42.808] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:55:0) after 1m0s
[19:16:42.967] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:55:0)
[19:16:42.967] Timer tick, asking Buildbucket for the build status
[19:16:43.502] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:43.502] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:56:0) after 1m0s
[19:17:43.596] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:56:0)
[19:17:43.596] Timer tick, asking Buildbucket for the build status
[19:17:43.755] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:43.755] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:57:0) after 1m0s
[19:18:43.818] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:57:0)
[19:18:43.818] Timer tick, asking Buildbucket for the build status
[19:18:44.477] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:44.477] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:58:0) after 1m0s
[19:19:44.547] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:58:0)
[19:19:44.547] Timer tick, asking Buildbucket for the build status
[19:19:45.000] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:45.000] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:59:0) after 1m0s
[19:20:45.868] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:59:0)
[19:20:45.868] Timer tick, asking Buildbucket for the build status
[19:20:47.735] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:47.735] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:60:0) after 1m0s
[19:21:47.875] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:60:0)
[19:21:47.875] Timer tick, asking Buildbucket for the build status
[19:21:48.124] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:48.124] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:61:0) after 1m0s
[19:22:48.509] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:61:0)
[19:22:48.509] Timer tick, asking Buildbucket for the build status
[19:22:50.055] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:50.055] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:62:0) after 1m0s
[19:23:50.216] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:62:0)
[19:23:50.216] Timer tick, asking Buildbucket for the build status
[19:23:50.428] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:50.428] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:63:0) after 1m0s
[19:24:50.556] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:63:0)
[19:24:50.556] Timer tick, asking Buildbucket for the build status
[19:24:50.854] Build 8895794601800593344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:50.854] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061224668317779552:64:0) after 1m0s
[19:25:38.828] Received PubSub notification, asking Buildbucket for the build status
[19:25:39.238] Build 8895794601800593344: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:25:39.238] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1574709937924267",
"created_by": "project:chromium",
"created_ts": "1574706186445882",
"id": "8895794601800593344",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"02be7d981436eba2394cdf3fcd611f1eb253225a\"}}",
"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-7-mn1y\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 165273, \"got_angle_revision\": \"04e074d1aae0aa1c3d9990ca147b17d5b6f96529\", \"got_dawn_revision\": \"8e9c3f663ccd270b8f047afe668e7cbc15e11bba\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"02be7d981436eba2394cdf3fcd611f1eb253225a\", \"got_revision_cp\": \"refs/heads/master@{#718715}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"afc52b0b26d7d34baee77ce7b75aa3c5625abca9\", \"got_v8_revision\": \"c4a36ad510e9e20ec4ce975add8f1fab3ae1359b\", \"got_v8_revision_cp\": \"refs/heads/8.0.357@{#1}\", \"got_webrtc_revision\": \"712a26f3842b4eba1f38c3ba7371b1cf771fd232\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#29902}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"02be7d981436eba2394cdf3fcd611f1eb253225a\", \"swarm_hashes_refs/heads/master(at){#718715}_without_patch\": {\"accessibility_unittests\": \"8b7a6fe3b457831a0320b2d8b2b27d6d108043d1\", \"angle_unittests\": \"3989cbf40f9de5ef81b5c0399c1f64161afcd83f\", \"app_shell_unittests\": \"f0a563eb60171c50954791bf88423a3176c19440\", \"aura_unittests\": \"ebd71558c38320c1357602891e0126a7efc0d4fd\", \"base_unittests\": \"7b71c16ee42c17504dfc025e94ad678b88b3b647\", \"base_util_unittests\": \"7d83d68b1d9b5078247639213301c235be9a49d7\", \"blink_common_unittests\": \"cbae067bd7df254cbbb94724d54bbb21b9872048\", \"blink_fuzzer_unittests\": \"c0bdf241ab486d4aa69848baac1aed9f1f8b6e87\", \"blink_heap_unittests\": \"1ab8e76863f2c0c95190b2ae67106acbc7c0b3bd\", \"blink_platform_unittests\": \"a243a57d8201fb5ff72e96e6e4e308944b83d0a0\", \"blink_python_tests\": \"326bc40f46ea58b025adc5b1391c4b8c34efefde\", \"blink_unittests\": \"67c77a1c713e3fd2ebe06debf8b00bffbd946400\", \"blink_web_tests_exparchive\": \"bcea8698eae14d6090f1ad59f0b05ca40ea13c68\", \"boringssl_crypto_tests\": \"b25078d2eef4d5e333da2869cba1005b88981f9d\", \"boringssl_ssl_tests\": \"5d25afc712279456eb3cfbd7527efcc8884e015a\", \"browser_tests\": \"66c129e3ae5339237559d025d7b1650046a0a500\", \"cacheinvalidation_unittests\": \"535446de102cc06e78db252fd27b74eae1406098\", \"capture_unittests\": \"6aca552d1db3ba7d6d3b2c46658f793c4733949f\", \"cast_unittests\": \"890396f073bb1ada9f9dd968991b846150ded0f6\", \"cc_unittests\": \"168013139164b712971aa0e3a6450653eaacca49\", \"chrome_app_unittests\": \"80492086e0e880807b25d527fa94307b1c9075ed\", \"chromedriver_unittests\": \"56c6ce982582397336045351e5f57c5a09928e1b\", \"color_unittests\": \"4017d27fc1c5b1ceb9ed21cd409108c66d971d4d\", \"components_browsertests\": \"cdc80a258857d8b2195632a4804367e1a28116d7\", \"components_unittests\": \"42d99f74b5beb8e8344d4964e2ad030b1b619777\", \"compositor_unittests\": \"b676f506313e6271de5189dfbf3e8c54a4c9ed6c\", \"content_browsertests\": \"2d79002d5452bd62203e877b496fd197e2d4b50b\", \"content_shell_crash_test\": \"b307a2a3d8e44f89be821ea08beae79cc1e07cc1\", \"content_unittests\": \"857e71f4bccbdc40950cfeb7e53c7d8dcb122fa0\", \"crashpad_tests\": \"c6bceb4cb683bd55e2f51bc8d73f6eb63792a6c6\", \"cronet_tests\": \"f0748c712807a82b088bdb57319f0f7840926788\", \"cronet_unittests\": \"a6f1e5c91559b0d4f528227a978166024ac3c3e7\", \"crypto_unittests\": \"e9e99f203ce39400701bdbe6882264cd400e011c\", \"dbus_unittests\": \"a7a20f26b2341972747435eea2e6de432730aaa2\", \"device_unittests\": \"492c7fcd00d7f2ffa9ddd1353098cf11ef405375\", \"devtools_lint_check\": \"e7642ad00dccf535555562cdcf8aa05d90c65ae0\", \"devtools_type_check\": \"acb2ea102c6f24e428df154faa5e3787767a51c3\", \"display_unittests\": \"8a00afc51a1da8c0dbcb244e10fe71b59bead509\", \"events_unittests\": \"7691a803074ba172d7a17f112a7ad3517accae4c\", \"extensions_browsertests\": \"1308ee30b958ed2a5c2b00625d0807ef939fec1d\", \"extensions_unittests\": \"d7157099ef63dd4e036e0a4e6441dc46a0137429\", \"filesystem_service_unittests\": \"1f0b79b998465774f22687a73adad709789f4fde\", \"flatbuffers_unittests\": \"68877d8b1262b0aa771438df1015a16a10098d5e\", \"gcm_unit_tests\": \"3a6147f52d44eabc0c00f58f47cac72045cffac6\", \"gfx_unittests\": \"199a0a988d9b11dcfb31e83f6a8aa29f05b1b05c\", \"gin_unittests\": \"9f74ca152a8d31c5472ff2c43d1a58448dc5dd04\", \"google_apis_unittests\": \"b594c3bb4b8aebddb91149163ea4d992f8ef620d\", \"gpu_unittests\": \"3d23c64ee8e454cf61be0c646d8a834ca8945901\", \"grit_python_unittests\": \"4267cdfe96cbb5881cc25cc5b15aab4b2f28c5f5\", \"gwp_asan_unittests\": \"f50b92c3616eeddd750b6771c9bd009160dc07b4\", \"headless_browsertests\": \"5df13979ef0ff61bc5d138202816f1607577937d\", \"headless_unittests\": \"f50dc1f583384d732a728165245efc4d32784421\", \"interactive_ui_tests\": \"8b126eb32dae0de43a0bbdffc87703013d16fd6d\", \"ipc_tests\": \"54b20a7f2df6b181aba06839527d46e34b6a83a4\", \"jingle_unittests\": \"d752f5a5042e959b4094fd1e21f1a32845b5e754\", \"latency_unittests\": \"b05d336f8564eafb6ba34fb4ebc9a5f181b18041\", \"libjingle_xmpp_unittests\": \"547a8cc3de8f9104992c932b8d9f69b4c4d9aa9f\", \"media_blink_unittests\": \"0102f6c252866c8fda4eabd4ca79dae8b00f0e2b\", \"media_service_unittests\": \"4f88c73887f743da15b3cbefcb343e62c30185d8\", \"media_unittests\": \"e02c263c0af7f8616a4bd0319d583297ff1ed6bb\", \"message_center_unittests\": \"af386f7c4c0264148e046089b1be3d7abfb10c28\", \"metrics_python_tests\": \"e8831493307ad8e6232616ae8b2b03175ce19840\", \"midi_unittests\": \"9820872de139700a7e9ab6fc2389f868613d4561\", \"mojo_core_unittests\": \"7996ccdc8737f8f54846479f588ebcf53b8f8dcc\", \"mojo_unittests\": \"a677e9812ceaad47ae8a8912d4c31faf25b8846e\", \"nacl_helper_nonsfi_unittests\": \"bfd7808c43b0f92c64660e518703483a1ccda2e8\", \"nacl_loader_unittests\": \"82b4b7d9d73d33c0f4256454c29b1b5b075f71bb\", \"native_theme_unittests\": \"910adee13b88abe8b9685044c6164b9ad4b14180\", \"net_unittests\": \"199cf43371d2302a6b92e89a6937f6d224db74d4\", \"pdf_unittests\": \"0a235c31cc7581bf6f844809eed0311f948f3e29\", \"perfetto_unittests\": \"136cf1308a04657eda965d8679e1fdfd51d5abaa\", \"ppapi_unittests\": \"5ec136b1eca3d3b4b6d7318f181e7778393917a8\", \"printing_unittests\": \"17578202b6e471f0058a63c5bda75b0bcfc3ca01\", \"remoting_unittests\": \"288189462e04b1b61f8e172a1411682275053162\", \"sandbox_linux_unittests\": \"cd3cfb40c9c2d7e4ae2c2f55931f7edc288bef58\", \"service_manager_unittests\": \"ebdd8616057ac9977c2922aebfa2f76de9f3a76f\", \"services_unittests\": \"1e05b737bcd5c521f802003bd5b305270b7b28d2\", \"shell_dialogs_unittests\": \"2bb0fac2996ac09296311c8461383f0cbd027460\", \"skia_unittests\": \"8ef24a3cf8063f93561d6bf1d6e0144e46783827\", \"snapshot_unittests\": \"ae956f62b4b9eb0f947553e513ae4a85870d7490\", \"sql_unittests\": \"33cb5315645b705ad0ef74eed8f65f6be34f9571\", \"storage_unittests\": \"e48543bed1e1d497c5079cf858385ec2117e9903\", \"sync_integration_tests\": \"2e9a85b0e8f4ebc4fbddccd16c83f272c25680cf\", \"telemetry_gpu_unittests\": \"8d55765d0e4e9b387556f53fda70829d47ae4a40\", \"telemetry_perf_unittests\": \"e785367e98d9b7cc29c7fae087474eff5439aea9\", \"telemetry_unittests\": \"f0eeb91165a3e547224b8e01931625729dd9079a\", \"traffic_annotation_auditor_unittests\": \"443cb07dbec4bf50a5955926e879b09ba80f1390\", \"ui_base_unittests\": \"5ecab4f3814073b83c52a984a0835f86364131a7\", \"ui_touch_selection_unittests\": \"ea5d7553224de5866ea25ca657bc61feb83cd149\", \"unit_tests\": \"ccdfee43c71a4041934e279bfd4dbada8cbf628c\", \"url_unittests\": \"f776ac5442af3938d5cca9948c5c7688f68b5d84\", \"views_perftests\": \"9c2cec55a221ca77d11a9e576f1388948af9da9d\", \"views_unittests\": \"9e9d98eba0ec38d358d1768c3cdb69a4ed0e4ca8\", \"viz_unittests\": \"9faa392a263ebd495afa289604a9766bc9f82c4c\", \"vr_common_unittests\": \"82d55d9e9ff24a76e8a2792119060f92a823a6c2\", \"vr_pixeltests\": \"ee98fdc7dd3379ec8f4e3ce39b5a844284af27af\", \"webdriver_wpt_tests\": \"e01ce5b74b4f5a36888676d3f919b3699b8c0946\", \"weblayer_browsertests\": \"82dee5d8ce47ef2fca2398d5488ca79e55acfedf\", \"wm_unittests\": \"71ce840a34c24dadd35d394531649702316bd499\", \"wtf_unittests\": \"1cd21114d587d66b54c224480316dbf1e1ca2d6f\", \"xr_browser_tests\": \"3712d62955aff8c2cc841c94ff9e1a105282f4ec\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_279625ee090c361ea8cf031374b7b020d1191c526e0ef1068ab634999c69ee70_v2\", \"builder_3f701152d865ce3ec8feb15f973214ad980aeace5cbda24477548a965269fca6_v2\", \"builder_5315cf154f9c1ae6b2621094599bc107171a849e1c0f232ec66a504ee800a364_v2\", \"builder_a8228d45a2e4046ab48e21b574ab916f6d3c7e4ad955b91040a9e9374d3dd18a_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-7-mn1y\"], \"image\": [\"chrome-xenial-19112100-3085293b610\"], \"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\": [\"4656-a09e2f8\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1574706481599742",
"status": "COMPLETED",
"status_changed_ts": "1574709938368958",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/165273",
"builder:Linux Builder (dbg)",
"buildset:commit/git/02be7d981436eba2394cdf3fcd611f1eb253225a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/02be7d981436eba2394cdf3fcd611f1eb253225a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9061224668317779552",
"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/8895794601800593344/+/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:48bc9f5ec3c2b610",
"user_agent:luci-scheduler"
],
"updated_ts": "1574709938379595",
"url": "https://ci.chromium.org/b/8895794601800593344",
"utcnow_ts": "1574709939192939"
}
}
[19:25:39.238] Invocation finished in 1h2m38.392929891s with status SUCCEEDED