[20:19:44.143] New invocation is queued and will start shortly
[20:19:45.238] Starting the invocation (attempt 1)
[20:19:45.276] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:19:45.276] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:19:45.276] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9054784940233548320",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"b50b951dc71bad5a6222893db2b6c51686ce5be9\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9054784940233548320",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/b50b951dc71bad5a6222893db2b6c51686ce5be9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b50b951dc71bad5a6222893db2b6c51686ce5be9",
"gitiles_ref:refs/heads/master"
]
}
[20:19:46.418] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1580847585437498",
"id": "8889354878210915104",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b50b951dc71bad5a6222893db2b6c51686ce5be9\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1580847586041235",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168229",
"builder:Linux Builder (dbg)",
"buildset:commit/git/b50b951dc71bad5a6222893db2b6c51686ce5be9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b50b951dc71bad5a6222893db2b6c51686ce5be9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054784940233548320",
"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/8889354878210915104/+/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": "1580847586041374",
"url": "https://ci.chromium.org/b/8889354878210915104",
"utcnow_ts": "1580847586405698"
}
}
[20:19:46.418] Task URL: https://ci.chromium.org/b/8889354878210915104
[20:19:46.418] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:2:0) after 1m0s
[20:20:46.568] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:2:0)
[20:20:46.568] Timer tick, asking Buildbucket for the build status
[20:20:46.849] Build 8889354878210915104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:20:46.849] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:3:0) after 1m0s
[20:21:46.882] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:3:0)
[20:21:46.882] Timer tick, asking Buildbucket for the build status
[20:21:47.063] Build 8889354878210915104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:21:47.063] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:4:0) after 1m0s
[20:22:47.106] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:4:0)
[20:22:47.106] Timer tick, asking Buildbucket for the build status
[20:22:47.437] Build 8889354878210915104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:22:47.437] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:5:0) after 1m0s
[20:23:47.562] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:5:0)
[20:23:47.562] Timer tick, asking Buildbucket for the build status
[20:23:47.756] Build 8889354878210915104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:23:47.756] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:6:0) after 1m0s
[20:24:14.936] Received PubSub notification, asking Buildbucket for the build status
[20:24:15.171] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:47.802] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:6:0)
[20:24:47.802] Timer tick, asking Buildbucket for the build status
[20:24:48.520] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:48.520] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:8:0) after 1m0s
[20:25:48.565] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:8:0)
[20:25:48.565] Timer tick, asking Buildbucket for the build status
[20:25:48.677] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:48.677] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:9:0) after 1m0s
[20:26:48.735] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:9:0)
[20:26:48.735] Timer tick, asking Buildbucket for the build status
[20:26:50.351] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:50.351] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:10:0) after 1m0s
[20:27:50.469] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:10:0)
[20:27:50.469] Timer tick, asking Buildbucket for the build status
[20:27:51.082] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:51.082] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:11:0) after 1m0s
[20:28:51.152] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:11:0)
[20:28:51.152] Timer tick, asking Buildbucket for the build status
[20:28:51.346] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:51.346] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:12:0) after 1m0s
[20:29:51.350] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:12:0)
[20:29:51.350] Timer tick, asking Buildbucket for the build status
[20:29:52.293] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:52.293] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:13:0) after 1m0s
[20:30:52.325] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:13:0)
[20:30:52.325] Timer tick, asking Buildbucket for the build status
[20:30:53.566] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:53.566] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:14:0) after 1m0s
[20:31:53.583] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:14:0)
[20:31:53.583] Timer tick, asking Buildbucket for the build status
[20:31:54.046] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:54.046] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:15:0) after 1m0s
[20:32:54.090] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:15:0)
[20:32:54.090] Timer tick, asking Buildbucket for the build status
[20:32:54.507] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:54.507] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:16:0) after 1m0s
[20:33:54.575] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:16:0)
[20:33:54.575] Timer tick, asking Buildbucket for the build status
[20:33:54.806] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:54.806] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:17:0) after 1m0s
[20:34:54.925] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:17:0)
[20:34:54.925] Timer tick, asking Buildbucket for the build status
[20:34:55.225] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:55.225] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:18:0) after 1m0s
[20:35:55.268] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:18:0)
[20:35:55.268] Timer tick, asking Buildbucket for the build status
[20:35:55.933] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:55.933] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:19:0) after 1m0s
[20:36:56.043] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:19:0)
[20:36:56.043] Timer tick, asking Buildbucket for the build status
[20:36:56.644] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:56.644] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:20:0) after 1m0s
[20:37:56.664] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:20:0)
[20:37:56.664] Timer tick, asking Buildbucket for the build status
[20:37:57.143] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:57.143] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:21:0) after 1m0s
[20:38:57.316] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:21:0)
[20:38:57.316] Timer tick, asking Buildbucket for the build status
[20:38:57.668] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:57.668] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:22:0) after 1m0s
[20:39:57.825] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:22:0)
[20:39:57.825] Timer tick, asking Buildbucket for the build status
[20:39:58.665] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:58.665] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:23:0) after 1m0s
[20:40:59.152] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:23:0)
[20:40:59.152] Timer tick, asking Buildbucket for the build status
[20:40:59.309] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:59.309] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:24:0) after 1m0s
[20:41:59.426] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:24:0)
[20:41:59.426] Timer tick, asking Buildbucket for the build status
[20:41:59.660] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:59.660] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:25:0) after 1m0s
[20:42:59.919] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:25:0)
[20:42:59.919] Timer tick, asking Buildbucket for the build status
[20:43:00.686] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:00.686] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:26:0) after 1m0s
[20:44:01.003] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:26:0)
[20:44:01.003] Timer tick, asking Buildbucket for the build status
[20:44:01.183] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:01.183] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:27:0) after 1m0s
[20:45:01.479] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:27:0)
[20:45:01.479] Timer tick, asking Buildbucket for the build status
[20:45:01.736] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:01.736] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:28:0) after 1m0s
[20:46:01.824] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:28:0)
[20:46:01.824] Timer tick, asking Buildbucket for the build status
[20:46:02.287] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:02.287] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:29:0) after 1m0s
[20:47:02.404] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:29:0)
[20:47:02.404] Timer tick, asking Buildbucket for the build status
[20:47:03.186] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:03.186] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:30:0) after 1m0s
[20:48:03.374] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:30:0)
[20:48:03.374] Timer tick, asking Buildbucket for the build status
[20:48:05.219] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:05.219] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:31:0) after 1m0s
[20:49:05.241] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:31:0)
[20:49:05.241] Timer tick, asking Buildbucket for the build status
[20:49:07.732] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:07.732] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:32:0) after 1m0s
[20:50:07.863] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:32:0)
[20:50:07.863] Timer tick, asking Buildbucket for the build status
[20:50:10.212] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:10.212] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:33:0) after 1m0s
[20:51:10.441] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:33:0)
[20:51:10.441] Timer tick, asking Buildbucket for the build status
[20:51:12.347] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:12.347] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:34:0) after 1m0s
[20:52:12.370] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:34:0)
[20:52:12.370] Timer tick, asking Buildbucket for the build status
[20:52:12.806] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:12.806] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:35:0) after 1m0s
[20:53:13.281] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:35:0)
[20:53:13.281] Timer tick, asking Buildbucket for the build status
[20:53:14.284] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:14.284] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:36:0) after 1m0s
[20:54:14.331] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:36:0)
[20:54:14.331] Timer tick, asking Buildbucket for the build status
[20:54:15.281] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:15.281] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:37:0) after 1m0s
[20:55:15.335] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:37:0)
[20:55:15.335] Timer tick, asking Buildbucket for the build status
[20:55:16.226] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:16.226] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:38:0) after 1m0s
[20:56:16.356] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:38:0)
[20:56:16.356] Timer tick, asking Buildbucket for the build status
[20:56:16.636] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:16.636] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:39:0) after 1m0s
[20:57:16.687] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:39:0)
[20:57:16.687] Timer tick, asking Buildbucket for the build status
[20:57:17.535] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:17.535] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:40:0) after 1m0s
[20:58:17.516] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:40:0)
[20:58:17.516] Timer tick, asking Buildbucket for the build status
[20:58:18.057] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:18.058] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:41:0) after 1m0s
[20:59:18.094] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:41:0)
[20:59:18.094] Timer tick, asking Buildbucket for the build status
[20:59:18.365] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:18.365] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:42:0) after 1m0s
[21:00:18.445] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:42:0)
[21:00:18.445] Timer tick, asking Buildbucket for the build status
[21:00:19.169] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:19.169] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:43:0) after 1m0s
[21:01:19.193] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:43:0)
[21:01:19.193] Timer tick, asking Buildbucket for the build status
[21:01:21.296] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:21.296] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:44:0) after 1m0s
[21:02:21.341] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:44:0)
[21:02:21.341] Timer tick, asking Buildbucket for the build status
[21:02:25.870] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:25.870] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:45:0) after 1m0s
[21:03:25.916] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:45:0)
[21:03:25.916] Timer tick, asking Buildbucket for the build status
[21:03:26.527] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:26.527] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:46:0) after 1m0s
[21:04:26.592] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:46:0)
[21:04:26.592] Timer tick, asking Buildbucket for the build status
[21:04:27.273] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:27.273] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:47:0) after 1m0s
[21:05:27.344] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:47:0)
[21:05:27.344] Timer tick, asking Buildbucket for the build status
[21:05:27.974] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:27.974] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:48:0) after 1m0s
[21:06:28.011] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:48:0)
[21:06:28.011] Timer tick, asking Buildbucket for the build status
[21:06:28.275] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:28.275] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:49:0) after 1m0s
[21:07:28.320] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:49:0)
[21:07:28.320] Timer tick, asking Buildbucket for the build status
[21:07:29.168] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:29.168] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:50:0) after 1m0s
[21:08:29.222] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:50:0)
[21:08:29.222] Timer tick, asking Buildbucket for the build status
[21:08:29.872] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:29.872] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:51:0) after 1m0s
[21:09:30.005] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:51:0)
[21:09:30.005] Timer tick, asking Buildbucket for the build status
[21:09:30.446] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:30.446] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:52:0) after 1m0s
[21:10:30.512] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:52:0)
[21:10:30.512] Timer tick, asking Buildbucket for the build status
[21:10:31.502] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:31.502] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:53:0) after 1m0s
[21:11:31.540] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:53:0)
[21:11:31.540] Timer tick, asking Buildbucket for the build status
[21:11:31.793] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:31.793] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:54:0) after 1m0s
[21:12:31.934] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:54:0)
[21:12:31.934] Timer tick, asking Buildbucket for the build status
[21:12:32.541] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:32.541] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:55:0) after 1m0s
[21:13:32.555] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:55:0)
[21:13:32.555] Timer tick, asking Buildbucket for the build status
[21:13:32.882] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:32.882] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:56:0) after 1m0s
[21:14:32.909] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:56:0)
[21:14:32.909] Timer tick, asking Buildbucket for the build status
[21:14:33.964] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:33.964] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:57:0) after 1m0s
[21:15:33.991] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:57:0)
[21:15:33.991] Timer tick, asking Buildbucket for the build status
[21:15:34.641] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:34.641] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:58:0) after 1m0s
[21:16:34.748] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:58:0)
[21:16:34.748] Timer tick, asking Buildbucket for the build status
[21:16:35.268] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:35.268] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:59:0) after 1m0s
[21:17:35.282] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:59:0)
[21:17:35.282] Timer tick, asking Buildbucket for the build status
[21:17:35.718] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:35.718] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:60:0) after 1m0s
[21:18:35.751] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:60:0)
[21:18:35.751] Timer tick, asking Buildbucket for the build status
[21:18:35.934] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:35.934] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:61:0) after 1m0s
[21:19:36.057] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:61:0)
[21:19:36.057] Timer tick, asking Buildbucket for the build status
[21:19:36.325] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:36.325] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:62:0) after 1m0s
[21:20:36.466] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:62:0)
[21:20:36.466] Timer tick, asking Buildbucket for the build status
[21:20:36.651] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:36.651] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:63:0) after 1m0s
[21:21:36.730] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:63:0)
[21:21:36.730] Timer tick, asking Buildbucket for the build status
[21:21:36.933] Build 8889354878210915104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:36.933] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054784940233548320:64:0) after 1m0s
[21:21:38.183] Received PubSub notification, asking Buildbucket for the build status
[21:21:38.367] Build 8889354878210915104: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:21:38.367] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1580851297367571",
"created_by": "project:chromium",
"created_ts": "1580847585437498",
"id": "8889354878210915104",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b50b951dc71bad5a6222893db2b6c51686ce5be9\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"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-32-uy4i\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168229, \"got_angle_revision\": \"d8c4b5fb80d417343b52273515e046a37eda7138\", \"got_dawn_revision\": \"47b15048a1fab6c360dc1594764506224655752b\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"b50b951dc71bad5a6222893db2b6c51686ce5be9\", \"got_revision_cp\": \"refs/heads/master@{#738308}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"ddee0f34e4cb30efe87d007204d6e83cf318f949\", \"got_v8_revision\": \"4460b2d2f5aa0f77cfd10a6c2301a95ad08aa9a5\", \"got_v8_revision_cp\": \"refs/heads/8.2.8@{#1}\", \"got_webrtc_revision\": \"0f6bcd18b2ef109850c6430fa0d474688571da5f\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30455}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b50b951dc71bad5a6222893db2b6c51686ce5be9\", \"swarm_hashes_refs/heads/master(at){#738308}_without_patch\": {\"accessibility_unittests\": \"bd1058848a6c1421daf38961030e25c521921bb8\", \"angle_unittests\": \"5236a25cc5ab382ecc083eebf0de36e82c7c735d\", \"app_shell_unittests\": \"469bc4ba32283192d56b6267d4718f0cd9feed18\", \"aura_unittests\": \"0cba0949a481a433f4766bf5188e97096eacdb06\", \"base_unittests\": \"30b55b0be198d5ee479928959dd4f1cc6c713970\", \"base_util_unittests\": \"fec8f2298fb005baac4c6000004bbd10b3bb305c\", \"blink_common_unittests\": \"36f50830e1a4bd3b8b9cab7c16b1b3f3bd1dc896\", \"blink_fuzzer_unittests\": \"a0fd8ea6d057211e3b2eba95ca2d0185a6ee9619\", \"blink_heap_unittests\": \"ff8582e0b964c4e69bced3227d8852113ab25d02\", \"blink_platform_unittests\": \"1a687958a9b4cdd15d497aa6d173937094f03ddf\", \"blink_python_tests\": \"c9ef650c721bf0c2eea270c763ad21692d3e367b\", \"blink_unittests\": \"30d5d6cd66da3bbc1db614730b47958f99918cb9\", \"blink_web_tests\": \"e951e00bdea824596f138d14806c7d69cf052404\", \"boringssl_crypto_tests\": \"55001752fb3cc5ce2a49f30c63351257468b8cbc\", \"boringssl_ssl_tests\": \"0b4b63b15fd49bcb95fb5077229534ce7876143d\", \"browser_tests\": \"efaed97d70d2df74dda433e47a25b5ae64754625\", \"cacheinvalidation_unittests\": \"fe5ba23a07838aeb20d936cdc010438cddaf58c3\", \"capture_unittests\": \"2e4f56ff141487886743116e0f811038bb5114e2\", \"cast_unittests\": \"eb72e8d348b19010bbb9fafd20d74d639ba59e9e\", \"cc_unittests\": \"38ec79e4e06d30199b7d7b3745cf8cd9bb5b6f52\", \"chrome_app_unittests\": \"e1a686c200f59c08b2fa60c180f7d00081a6f61e\", \"chromedriver_unittests\": \"5816a2629b8ce6fa38b3adc7a74d597429323d6c\", \"components_browsertests\": \"46968d8d6eee1590a2d8be1379932984157663da\", \"components_unittests\": \"a404b8002a68879e419b8916ab5703403d3e3927\", \"compositor_unittests\": \"50b23f5babd6703c36cf0e331c4054cf1f94e863\", \"content_browsertests\": \"652efb118a7f24eda136b29e190bbe512da1b415\", \"content_shell_crash_test\": \"88385882e6d6b3bff49a0d32b2e1b32c281a9d53\", \"content_unittests\": \"bdcc4c13fcf4e9b746eda7e4061893ba495e7c68\", \"crashpad_tests\": \"32fb0e27f7a6df1169c3ac1a15e5dbb923fca800\", \"cronet_tests\": \"523f8ba120c6b18cf0d68c9f5a9e04f854b8815c\", \"cronet_unittests\": \"aefbc0167b4f405384453be74d04a4780b860d12\", \"crypto_unittests\": \"e24486b2ebd869c3a99a0e905769850d13500448\", \"dbus_unittests\": \"866402b1fad4635a8f9e805bcda03aad4d898e5f\", \"device_unittests\": \"4100eb754cbdaa94346ab5b1481b359abbf37052\", \"display_unittests\": \"a6e061eb91025e0910f2bc4fc756223c250e5275\", \"events_unittests\": \"bfbd8ab55590ebee23881ea00824294db7b4ca34\", \"extensions_browsertests\": \"af557760d26f4925b03ffef688cdb8f72707d0fa\", \"extensions_unittests\": \"1bc4c8b68313770016b389343de3f96908568395\", \"filesystem_service_unittests\": \"393e8226bf3e9a5d93b7b4b59c2c15ad42107d2b\", \"flatbuffers_unittests\": \"ca71348ce5934d53fb892115f8e6e8a2436d5e6c\", \"gcm_unit_tests\": \"d898f920bbc5e413da368ff9b0d3ccd15324b371\", \"gfx_unittests\": \"5c1872147c48ed55b35cee25efaa4c8ec36bbd8b\", \"gin_unittests\": \"67a1941f0d9cb462c2c13369b80f548e7ce8804f\", \"google_apis_unittests\": \"5dcc7b8966b28acc07a8a4e64ddf5c0159a70e4e\", \"gpu_unittests\": \"796a5477365c07fcfb51add482204e3bfb504061\", \"grit_python_unittests\": \"a906de1211aa1e6d0b6deb8a9a162d6998fb6336\", \"gwp_asan_unittests\": \"4279aeb12a12b14b127ab3cb4d99402dd2c0da03\", \"headless_browsertests\": \"3004b25a437dbe4e0ee539d73cfaa4741a2587b6\", \"headless_unittests\": \"4d7929556f78adcdebaf6e46159ee261f710c678\", \"interactive_ui_tests\": \"c11797f7f00dce5f0c3e202ccc212f1e8e848a1f\", \"ipc_tests\": \"28598dc966f108544c67f1dd76744c637d408032\", \"jingle_unittests\": \"d3d59ecd2a14350bbffbe484c9ef14146da7d722\", \"latency_unittests\": \"3c61ad1f1f37df2f917531bba50e56302d9e5287\", \"libjingle_xmpp_unittests\": \"3eda7abe6522e78b1756ddf361e9a901ef93f6f2\", \"media_blink_unittests\": \"ac79556db85c553b69b0bc6a2f13c9ed8b6b84f3\", \"media_service_unittests\": \"98a787d449d98aec7dfa2380b8dc65ce83c8beb8\", \"media_unittests\": \"5e462944b9c23b1acccb1cea26575cd70afd0356\", \"message_center_unittests\": \"b207ef4ebe0dbcc4ff0545fac7e364944bcd7225\", \"metrics_python_tests\": \"90d5704981a94dd2de2ec028aaf2c1afaf388bfa\", \"midi_unittests\": \"d75a0d340c6c09f0b1eb26484e2c99500b0b3e5b\", \"mojo_core_unittests\": \"1b069d9e5f53a850ecce38f021d8f7bbc0cd0462\", \"mojo_unittests\": \"27dd8c1781c950b55f868b2f5046e87df047497c\", \"nacl_helper_nonsfi_unittests\": \"df2f6e3b80eb2aafd2bb5734c70bd4eec190a30b\", \"nacl_loader_unittests\": \"0f4ff4b8c8bf2f54a4f2aca4333dea862f9d671e\", \"native_theme_unittests\": \"a4ad1b583300ca509dffc919060ea93bdc933f4a\", \"net_unittests\": \"6e853859fb83f633de6dec0fab35d89e428ce4f2\", \"pdf_unittests\": \"71d4ebf7d00a0865ab5faf90c8cdec307ee9c92f\", \"perfetto_unittests\": \"600bf0784c49f904b23d105d15e7d8aaf5540b09\", \"ppapi_unittests\": \"032f2d9817a84fdc1ba86df8b738d3707da63b8c\", \"printing_unittests\": \"6b8023816aa00c45141fb54640f08700df87ecc9\", \"remoting_unittests\": \"a5c95311c145808b7015e461a704694b8acc18bd\", \"sandbox_linux_unittests\": \"91e24d064c0ccbec54c1f566991c7b40b669f51c\", \"service_manager_unittests\": \"0b38cc6ee62d437ddf0ac3541f7467b78adb5d79\", \"services_unittests\": \"3b746e6f5e7157b930a51b1641a3d87636bcca1d\", \"shell_dialogs_unittests\": \"9074ad7adcb7f52826931facb5ea8c87113791ef\", \"skia_unittests\": \"4ff101f73550152c40af3eedb97dbed3f9b95298\", \"snapshot_unittests\": \"eda499a1a71b868026ee8778156f5c8accfff248\", \"sql_unittests\": \"ff6b0f4a89dd3a31d4032aeb99078c078d3d3fe9\", \"storage_unittests\": \"a541e0ce58faf93e86c870d2eb42eab7653a8d5a\", \"sync_integration_tests\": \"a63ccc91e15b0f79ec7fa4f96e5239faffcd6d6e\", \"telemetry_gpu_unittests\": \"cde3926958758c87a399228376f3dd500d8a5af5\", \"telemetry_perf_unittests\": \"607e32d82545684dbef4f4e0fc5af9f5e7922243\", \"telemetry_unittests\": \"02d209604f5e8dedbdcd06c1a582b3f29169a58e\", \"traffic_annotation_auditor_unittests\": \"14b0029d593486acf745d79528a1d98ba3273c92\", \"ui_base_unittests\": \"c85c8a511bad970b6bda6ec1495087c5a18204f2\", \"ui_touch_selection_unittests\": \"4ec67ae1936e668933fc820a9b37af854ea6c6eb\", \"unit_tests\": \"558237789bb614ca66326dbd6afdb1502dfc4b16\", \"url_unittests\": \"08a98042306f9a85a0349bf20655c9142851de3f\", \"views_perftests\": \"b73eaba9d6a4fbb907e659be77ac9a7a5092598e\", \"views_unittests\": \"17f281870a8a630522452ce4ac731dc92b09c888\", \"viz_unittests\": \"926148efec0d5081e44e298fb93a7055921e3ba3\", \"vr_common_unittests\": \"4cea9d049fa8150555d8170683dd1fd11b9a2507\", \"vr_pixeltests\": \"24c9b0fbf70597afee665d38652a1c474ef21df5\", \"webdriver_wpt_tests\": \"518ab21a02bc6044001694149e7d7589b99f2f0a\", \"weblayer_browsertests\": \"052756aad8380f1ec8f35a8c654f0468c7079bdb\", \"wm_unittests\": \"1edc2a8918bb5de3ee8d81e560c2f16675750f76\", \"wtf_unittests\": \"59e8fa716dfe8e5359f55ec760b681a64874ef84\", \"xr_browser_tests\": \"5ad3fd33b3043dbacdb29c43753052cc2cb669e3\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0cd6cf41ec42413cc9e5bdc0dde565dc7bee061bff1a52db1e25d07760950df7_v2\", \"builder_26ee0c713798d591dc066c3b18aba10c428b81e49e5d430ca5b1f8f50ef6f654_v2\", \"builder_387e931c7b633ee2f48b892df14288ffa2061feda74b30444810d565fa474120_v2\", \"builder_8851890e994e29b56fba2042f90feba99073c8e56d0ea67af5ee13ebe9f87cf6_v2\", \"builder_98a60cf4ba341a05f49601a94ea91d205798f47ee731869ed66ec9c0f2c54ce9_v2\", \"builder_a432d290b72bc0f3cae401fbd647c33c14340f64e89770e9c9c39d6230495526_v2\", \"builder_ddf530b99db2c48999a16b04f27f886d0f06df616543b36733eb8d0558bd23f5_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-32-uy4i\"], \"image\": [\"chrome-xenial-20012600-8e1a7e485b7\"], \"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\": [\"4809-bb2013c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1580847852145941",
"status": "COMPLETED",
"status_changed_ts": "1580851297675372",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168229",
"builder:Linux Builder (dbg)",
"buildset:commit/git/b50b951dc71bad5a6222893db2b6c51686ce5be9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b50b951dc71bad5a6222893db2b6c51686ce5be9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054784940233548320",
"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/8889354878210915104/+/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:4a2aad6574eca510",
"user_agent:luci-scheduler"
],
"updated_ts": "1580851297680528",
"url": "https://ci.chromium.org/b/8889354878210915104",
"utcnow_ts": "1580851298314165"
}
}
[21:21:38.367] Invocation finished in 1h1m54.24162385s with status SUCCEEDED