[23:34:06.303] New invocation is queued and will start shortly
[23:34:07.654] Starting the invocation (attempt 1)
[23:34:07.715] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:34:07.715] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:34:07.715] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9059574350800715824",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"3eada85e379983fdc972bd43200b6881d9c208e7\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9059574350800715824",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/3eada85e379983fdc972bd43200b6881d9c208e7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3eada85e379983fdc972bd43200b6881d9c208e7",
"gitiles_ref:refs/heads/master"
]
}
[23:34:09.612] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1576280048803242",
"id": "8894144287504835808",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3eada85e379983fdc972bd43200b6881d9c208e7\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1576280049278128",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166061",
"builder:Linux Builder (dbg)",
"buildset:commit/git/3eada85e379983fdc972bd43200b6881d9c208e7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3eada85e379983fdc972bd43200b6881d9c208e7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059574350800715824",
"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/8894144287504835808/+/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": "1576280049278235",
"url": "https://ci.chromium.org/b/8894144287504835808",
"utcnow_ts": "1576280049601451"
}
}
[23:34:09.612] Task URL: https://ci.chromium.org/b/8894144287504835808
[23:34:09.612] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:2:0) after 1m0s
[23:35:09.648] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:2:0)
[23:35:09.648] Timer tick, asking Buildbucket for the build status
[23:35:10.325] Build 8894144287504835808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:35:10.325] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:3:0) after 1m0s
[23:36:10.354] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:3:0)
[23:36:10.354] Timer tick, asking Buildbucket for the build status
[23:36:10.520] Build 8894144287504835808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:36:10.520] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:4:0) after 1m0s
[23:37:10.593] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:4:0)
[23:37:10.593] Timer tick, asking Buildbucket for the build status
[23:37:11.019] Build 8894144287504835808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:37:11.019] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:5:0) after 1m0s
[23:38:11.072] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:5:0)
[23:38:11.072] Timer tick, asking Buildbucket for the build status
[23:38:12.016] Build 8894144287504835808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:38:12.016] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:6:0) after 1m0s
[23:39:05.881] Received PubSub notification, asking Buildbucket for the build status
[23:39:06.202] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:12.063] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:6:0)
[23:39:12.063] Timer tick, asking Buildbucket for the build status
[23:39:12.296] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:12.296] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:8:0) after 1m0s
[23:40:12.354] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:8:0)
[23:40:12.354] Timer tick, asking Buildbucket for the build status
[23:40:12.619] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:12.619] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:9:0) after 1m0s
[23:41:12.687] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:9:0)
[23:41:12.687] Timer tick, asking Buildbucket for the build status
[23:41:13.007] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:13.007] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:10:0) after 1m0s
[23:42:13.003] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:10:0)
[23:42:13.003] Timer tick, asking Buildbucket for the build status
[23:42:13.582] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:13.582] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:11:0) after 1m0s
[23:43:13.683] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:11:0)
[23:43:13.683] Timer tick, asking Buildbucket for the build status
[23:43:13.950] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:13.950] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:12:0) after 1m0s
[23:44:14.032] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:12:0)
[23:44:14.032] Timer tick, asking Buildbucket for the build status
[23:44:14.697] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:14.697] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:13:0) after 1m0s
[23:45:14.713] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:13:0)
[23:45:14.714] Timer tick, asking Buildbucket for the build status
[23:45:15.104] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:15.104] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:14:0) after 1m0s
[23:46:15.141] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:14:0)
[23:46:15.141] Timer tick, asking Buildbucket for the build status
[23:46:15.584] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:15.584] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:15:0) after 1m0s
[23:47:15.639] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:15:0)
[23:47:15.639] Timer tick, asking Buildbucket for the build status
[23:47:15.779] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:15.779] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:16:0) after 1m0s
[23:48:15.860] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:16:0)
[23:48:15.860] Timer tick, asking Buildbucket for the build status
[23:48:16.383] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:16.383] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:17:0) after 1m0s
[23:49:16.528] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:17:0)
[23:49:16.528] Timer tick, asking Buildbucket for the build status
[23:49:16.745] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:16.745] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:18:0) after 1m0s
[23:50:16.767] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:18:0)
[23:50:16.767] Timer tick, asking Buildbucket for the build status
[23:50:18.532] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:18.532] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:19:0) after 1m0s
[23:51:18.757] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:19:0)
[23:51:18.757] Timer tick, asking Buildbucket for the build status
[23:51:19.661] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:19.661] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:20:0) after 1m0s
[23:52:19.654] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:20:0)
[23:52:19.654] Timer tick, asking Buildbucket for the build status
[23:52:19.846] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:19.846] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:21:0) after 1m0s
[23:53:20.170] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:21:0)
[23:53:20.170] Timer tick, asking Buildbucket for the build status
[23:53:20.884] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:20.884] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:22:0) after 1m0s
[23:54:20.912] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:22:0)
[23:54:20.912] Timer tick, asking Buildbucket for the build status
[23:54:21.459] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:21.459] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:23:0) after 1m0s
[23:55:21.692] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:23:0)
[23:55:21.692] Timer tick, asking Buildbucket for the build status
[23:55:22.469] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:22.469] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:24:0) after 1m0s
[23:56:22.600] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:24:0)
[23:56:22.600] Timer tick, asking Buildbucket for the build status
[23:56:25.833] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:25.833] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:25:0) after 1m0s
[23:57:25.956] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:25:0)
[23:57:25.956] Timer tick, asking Buildbucket for the build status
[23:57:26.284] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:26.284] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:26:0) after 1m0s
[23:58:26.446] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:26:0)
[23:58:26.446] Timer tick, asking Buildbucket for the build status
[23:58:26.862] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:26.862] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:27:0) after 1m0s
[23:59:26.936] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:27:0)
[23:59:26.936] Timer tick, asking Buildbucket for the build status
[23:59:27.399] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:27.399] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:28:0) after 1m0s
[00:00:27.435] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:28:0)
[00:00:27.435] Timer tick, asking Buildbucket for the build status
[00:00:28.134] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:28.134] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:29:0) after 1m0s
[00:01:28.253] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:29:0)
[00:01:28.253] Timer tick, asking Buildbucket for the build status
[00:01:28.628] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:28.628] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:30:0) after 1m0s
[00:02:28.791] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:30:0)
[00:02:28.791] Timer tick, asking Buildbucket for the build status
[00:02:29.018] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:29.018] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:31:0) after 1m0s
[00:03:29.117] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:31:0)
[00:03:29.117] Timer tick, asking Buildbucket for the build status
[00:03:29.619] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:29.619] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:32:0) after 1m0s
[00:04:29.785] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:32:0)
[00:04:29.786] Timer tick, asking Buildbucket for the build status
[00:04:31.985] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:31.985] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:33:0) after 1m0s
[00:05:32.089] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:33:0)
[00:05:32.089] Timer tick, asking Buildbucket for the build status
[00:05:33.150] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:33.150] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:34:0) after 1m0s
[00:06:33.213] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:34:0)
[00:06:33.213] Timer tick, asking Buildbucket for the build status
[00:06:33.616] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:33.616] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:35:0) after 1m0s
[00:07:33.673] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:35:0)
[00:07:33.673] Timer tick, asking Buildbucket for the build status
[00:07:34.152] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:34.152] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:36:0) after 1m0s
[00:08:34.308] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:36:0)
[00:08:34.308] Timer tick, asking Buildbucket for the build status
[00:08:35.196] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:35.196] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:37:0) after 1m0s
[00:09:35.253] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:37:0)
[00:09:35.253] Timer tick, asking Buildbucket for the build status
[00:09:35.620] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:35.620] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:38:0) after 1m0s
[00:10:35.767] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:38:0)
[00:10:35.767] Timer tick, asking Buildbucket for the build status
[00:10:36.348] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:36.348] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:39:0) after 1m0s
[00:11:36.416] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:39:0)
[00:11:36.416] Timer tick, asking Buildbucket for the build status
[00:11:36.761] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:36.761] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:40:0) after 1m0s
[00:12:36.983] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:40:0)
[00:12:36.983] Timer tick, asking Buildbucket for the build status
[00:12:38.312] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:38.312] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:41:0) after 1m0s
[00:13:38.370] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:41:0)
[00:13:38.370] Timer tick, asking Buildbucket for the build status
[00:13:38.689] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:38.689] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:42:0) after 1m0s
[00:14:39.078] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:42:0)
[00:14:39.078] Timer tick, asking Buildbucket for the build status
[00:14:39.214] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:39.215] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:43:0) after 1m0s
[00:15:39.979] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:43:0)
[00:15:39.979] Timer tick, asking Buildbucket for the build status
[00:15:40.525] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:40.525] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:44:0) after 1m0s
[00:16:40.641] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:44:0)
[00:16:40.641] Timer tick, asking Buildbucket for the build status
[00:16:40.849] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:40.849] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:45:0) after 1m0s
[00:17:40.908] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:45:0)
[00:17:40.908] Timer tick, asking Buildbucket for the build status
[00:17:43.171] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:43.171] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:46:0) after 1m0s
[00:18:43.176] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:46:0)
[00:18:43.176] Timer tick, asking Buildbucket for the build status
[00:18:44.924] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:44.924] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:47:0) after 1m0s
[00:19:45.155] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:47:0)
[00:19:45.155] Timer tick, asking Buildbucket for the build status
[00:19:46.223] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:46.223] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:48:0) after 1m0s
[00:20:46.577] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:48:0)
[00:20:46.577] Timer tick, asking Buildbucket for the build status
[00:20:47.557] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:47.557] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:49:0) after 1m0s
[00:21:47.796] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:49:0)
[00:21:47.796] Timer tick, asking Buildbucket for the build status
[00:21:48.110] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:48.110] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:50:0) after 1m0s
[00:22:48.180] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:50:0)
[00:22:48.180] Timer tick, asking Buildbucket for the build status
[00:22:51.428] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:51.428] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:51:0) after 1m0s
[00:23:51.722] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:51:0)
[00:23:51.722] Timer tick, asking Buildbucket for the build status
[00:23:52.087] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:52.087] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:52:0) after 1m0s
[00:24:52.382] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:52:0)
[00:24:52.382] Timer tick, asking Buildbucket for the build status
[00:24:53.144] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:53.144] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:53:0) after 1m0s
[00:25:53.166] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:53:0)
[00:25:53.166] Timer tick, asking Buildbucket for the build status
[00:25:53.609] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:53.609] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:54:0) after 1m0s
[00:26:53.688] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:54:0)
[00:26:53.688] Timer tick, asking Buildbucket for the build status
[00:26:53.911] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:53.911] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:55:0) after 1m0s
[00:27:53.920] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:55:0)
[00:27:53.920] Timer tick, asking Buildbucket for the build status
[00:27:54.951] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:54.951] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:56:0) after 1m0s
[00:28:55.017] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:56:0)
[00:28:55.017] Timer tick, asking Buildbucket for the build status
[00:28:58.528] Build 8894144287504835808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:58.528] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9059574350800715824:57:0) after 1m0s
[00:29:01.696] Received PubSub notification, asking Buildbucket for the build status
[00:29:01.938] Build 8894144287504835808: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:29:01.938] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1576283340783604",
"created_by": "project:chromium",
"created_ts": "1576280048803242",
"id": "8894144287504835808",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3eada85e379983fdc972bd43200b6881d9c208e7\"}}",
"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-114-2pe0\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 166061, \"got_angle_revision\": \"e181f2f7cf172afd95b9646283bd910caaa1b721\", \"got_dawn_revision\": \"77fd4750ae5ad3ee9f281089fddfeaca56ed049c\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"3eada85e379983fdc972bd43200b6881d9c208e7\", \"got_revision_cp\": \"refs/heads/master@{#724828}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"fbbfeb700295d1fe1ec6419a93594e5e1540ea32\", \"got_v8_revision\": \"259dff3db0cc5126c95efc07addd5b380b93da48\", \"got_v8_revision_cp\": \"refs/heads/8.1.30@{#1}\", \"got_webrtc_revision\": \"41466b7bef5aa0927e3724f53c556bc4019f80d4\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30087}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3eada85e379983fdc972bd43200b6881d9c208e7\", \"swarm_hashes_refs/heads/master(at){#724828}_without_patch\": {\"accessibility_unittests\": \"36de3dbdb1b9082da712d0b537b67412ca014f27\", \"angle_unittests\": \"9e58aaadfe44ff24d94b46b61566fd5ce521ba65\", \"app_shell_unittests\": \"36e22fb3c6b5e62c097737fe97b30718fae9e08b\", \"aura_unittests\": \"055dfcca55f7a4dff4320f98c4ea44da4ee75bbc\", \"base_unittests\": \"17e2f26bd3a123087922c6ac6c57bf29cdfa3ea7\", \"base_util_unittests\": \"1e86965993f6abfefcd6e40f4a47efcd763a66a8\", \"blink_common_unittests\": \"4d63ecef7a873079a668d3ef0c4606cb1249ec1b\", \"blink_fuzzer_unittests\": \"c0b560f19ee168af2aeeda1b5e6e52fb1f33f8e1\", \"blink_heap_unittests\": \"f6977d4d0e463a6492c8a061d9790e366b845403\", \"blink_platform_unittests\": \"da16b66951c9867fa5e8143fa542929a53418c3e\", \"blink_python_tests\": \"49a0adcf61062f780712f9aa38d4b7596d48827b\", \"blink_unittests\": \"22696c66438ce40a2591dde48d11449084a3f794\", \"blink_web_tests\": \"3b52f1481527ed75233a57e920e039591ff2b35f\", \"boringssl_crypto_tests\": \"762c5d7b61e30290ef501c9e23935d21eabceb5c\", \"boringssl_ssl_tests\": \"eb64477d9c27e4c191f648d8e0eda2ce2eb9cbe8\", \"browser_tests\": \"e7b67200030a6edbd0af375220928a71a797073d\", \"cacheinvalidation_unittests\": \"ebe3d0d7ad2a1edc178d5a3055431e8199eec9fa\", \"capture_unittests\": \"6ee14a0729d4e148cfeef2b4162d562bf3de6a5e\", \"cast_unittests\": \"50f4109f00d436f928f6c56af81840ffc8bdf669\", \"cc_unittests\": \"97c5c002b2cbc68e16bab859f778dd94e79c29a2\", \"chrome_app_unittests\": \"d185a05efc0b230578099fe01b1fddd08c3a7153\", \"chromedriver_unittests\": \"58fdc55c80a07f7da36ebf929004adc83cbdb0c5\", \"components_browsertests\": \"cab4572cdbea716767f36880c94ff6b5de873f96\", \"components_unittests\": \"2b7faceac25031c485f5fd2eca4cdd2662a5c6b2\", \"compositor_unittests\": \"10a311bb863db44ca409296c69ae9cec6e3a189b\", \"content_browsertests\": \"fed2dc624f61cea278f834962f62d4b8d7c34b99\", \"content_shell_crash_test\": \"96221c240b0a23f9a2d4a30d1185ad12e46a93a3\", \"content_unittests\": \"84a8432edd28d7952d3de62db02adc78a7e29a7d\", \"crashpad_tests\": \"70df60c38a2134bf5e29bfcb16b550ccb4df44a1\", \"cronet_tests\": \"9b5e007508889f47aa1264782e7984e65d403cd9\", \"cronet_unittests\": \"dd93a236073926f14a14ba5fa26fd388f32e9262\", \"crypto_unittests\": \"b91f84d3aab8ba695dbe1cd8654ed5e23c8230d5\", \"dbus_unittests\": \"03ac25fc2fc64db882cb31931aeeb91654388863\", \"device_unittests\": \"79885aabe984cce1318346f30778c626a1c6bf5d\", \"devtools_lint_check\": \"1bf2f108f8410c21e2a04749bd05c0eb49e79b0e\", \"devtools_type_check\": \"d1c26d88e2a8ccfac08e83823baf80cce4317e5c\", \"display_unittests\": \"1b5cf1f60d30105c118970dbd709f02177b29f78\", \"events_unittests\": \"3982fd5231161d8d8fa2976e32be995efacf127d\", \"extensions_browsertests\": \"a5eb788a6da49f32d75d078ae02f33e0532f768d\", \"extensions_unittests\": \"4acaa202bff2b22eeeaa8b47d96c4528425e7308\", \"filesystem_service_unittests\": \"2da3f55433ddd4f2c910473bc4a87e6274d1fb2e\", \"flatbuffers_unittests\": \"034d1c63a8199a680c9bbf29b5866b1c851e28ee\", \"gcm_unit_tests\": \"53d0cc137a7662f7144aae9e179b1e96bfdd7452\", \"gfx_unittests\": \"e1ad14e145cfc0986609ce45631ea1bfd23025c2\", \"gin_unittests\": \"3cb2846707e1620a42b4d4545de1e4b7223bb144\", \"google_apis_unittests\": \"0d20aaf9ca11b48c36acdfa3cd47b9d90785f098\", \"gpu_unittests\": \"7bdafd67bbc3964c637c363c3401e57537e39ef3\", \"grit_python_unittests\": \"25dff9ef6327c596609d376409bc1d0e605b6d43\", \"gwp_asan_unittests\": \"91b38ceb9a647fd5094a632f4105619294ae8aa4\", \"headless_browsertests\": \"31f3530e2d5af0f4156693721c980de8f88fbaa3\", \"headless_unittests\": \"be1224feeb1a25cdb3d1035926e6688c172bd08a\", \"interactive_ui_tests\": \"24b04a3c38bf57082a2a98000f73e3c78b7c20e9\", \"ipc_tests\": \"9a4c9058bf387cc2b1810992b303bb8840a70554\", \"jingle_unittests\": \"45dab5324f59b268ab23ee62989e99516fb1299a\", \"latency_unittests\": \"bafe5437de38d3d6303040da55c418346d65f459\", \"libjingle_xmpp_unittests\": \"427002d6b590e1a07313ad474798669a92f849af\", \"media_blink_unittests\": \"3d299ef88d28f523a735a82a1e094ceee5df508d\", \"media_service_unittests\": \"6c7d0ac85c7d70f47accc4f6458c446dc76dcf77\", \"media_unittests\": \"777bfd6bc8a7bb8e99567986459fef8cc24ba16c\", \"message_center_unittests\": \"0f24e78a15269b58bc09e4e3c9e0af304e4e8e2f\", \"metrics_python_tests\": \"3acb2321964e773db83fa2e3825d07e7d4667838\", \"midi_unittests\": \"3da531bb444e7fbef80177061d3b556c9674731d\", \"mojo_core_unittests\": \"ac7388f1a2fa4fd659643df23057dadb08358954\", \"mojo_unittests\": \"ebf73033da56b602b968167248b789d03cf41e02\", \"nacl_helper_nonsfi_unittests\": \"68a8df82103ca892cf6b79ff49a865cba05b5936\", \"nacl_loader_unittests\": \"4558e54b89d391721076626b6228ea108caf7acf\", \"native_theme_unittests\": \"fba2ef20f6044791b1a0e5c244667e06a12bb76e\", \"net_unittests\": \"4899f1049863c7a6009524caf86ffd0cc680bd46\", \"pdf_unittests\": \"2eddf66594034d29e1e5a30c7c61c6ea6f8a0460\", \"perfetto_unittests\": \"a4eef1a54d3bc9010ed2d447c2dafe89ac8e631f\", \"ppapi_unittests\": \"cd03e083151d704cb8e6130e61322c034243faab\", \"printing_unittests\": \"d8b2b93c4ce4899b369da5cd4666cbbd81ef5778\", \"remoting_unittests\": \"0bf0436c29478b31dc568bb4d00b6ccf8fef404f\", \"sandbox_linux_unittests\": \"3a8eb7fd769099b71fc3ad8ae04066339a946e7c\", \"service_manager_unittests\": \"09daa545358c6b0796315ca2d000d51dd0785983\", \"services_unittests\": \"cf3a650dd291a3fd5c388476f6e591ce10550256\", \"shell_dialogs_unittests\": \"2fb78bbbdc484b6e0fd83aa9bd51051db79ee949\", \"skia_unittests\": \"a1c796f5b789721df23ebe4488c506496383e3c4\", \"snapshot_unittests\": \"4dbfe81d1f1d33305c3ae3f4cedfce68020bba95\", \"sql_unittests\": \"98f45ce7e8c02a65fc5dd81dc278dabb469c5a77\", \"storage_unittests\": \"fedcf3a7740324e221a05ac579c0a29c8e0286d4\", \"sync_integration_tests\": \"28a234f40088535c12a0d10bd8dc109b9bf687c3\", \"telemetry_gpu_unittests\": \"4005810fc60db156d26912b4f294e99baf5d871e\", \"telemetry_perf_unittests\": \"b065ed88ed1fb5093f10064c75d3337c5bc60c97\", \"telemetry_unittests\": \"4d174bc06a03da5a93bf2e4037d6dc2d1728a247\", \"traffic_annotation_auditor_unittests\": \"e0bee186b0684c053ed26ddf6a127bb85d27c949\", \"ui_base_unittests\": \"d3340c470394cd6ffd0bccb0c0592ae707682e0d\", \"ui_touch_selection_unittests\": \"3fb2bdf18aa9e0edea4ae9af9de4de4c4ddb7750\", \"unit_tests\": \"3f1085f54445b7ff4f0031ab8e1a65095fcc973d\", \"url_unittests\": \"35103aebf1eac7c72048853b41ae9d869d40a3a5\", \"views_perftests\": \"c56b69fbaa1e93a24fbe95fc3d6b678d34a0baf5\", \"views_unittests\": \"0cbe1f449e6aaf46355c423c46bc42b4c9d42ea5\", \"viz_unittests\": \"83dc5a4dea181fb3eefcd0b400cdc5f1a24ec316\", \"vr_common_unittests\": \"b3187f2332ae2ed004324393ff59b2b2521553a8\", \"vr_pixeltests\": \"38ee2d88e650ba2318d6628192bcf97976bbd601\", \"webdriver_wpt_tests\": \"903a65b7d2a2c1be3c5bf7b88913de9c9786d8b6\", \"weblayer_browsertests\": \"290b1953967c02b3a0a32add1396fb2d3a13f1af\", \"wm_unittests\": \"e82a6a10633f0e2cfa5167949f4a518619fc7ca2\", \"wtf_unittests\": \"65ee1b6519927cb842f588f87eb00518ef1bdb3a\", \"xr_browser_tests\": \"c829b74e7ccad15be9a987aa870f115dc0f33843\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_65ed874bd033a5984c3cbb528261ef1b136b40627f077f473aaaca27b3fa3caf_v2\", \"builder_957c0f1a1c74c3ada6665996f2ff614223d5da32e2cc2316ff7cc4c9c4beae5c_v2\", \"builder_fa0031875b7f2f11c46443055ab400bfaeb07a1d2a142b58c631314016c04e0a_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-114-2pe0\"], \"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\": [\"4724-8894a57\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1576280344107607",
"status": "COMPLETED",
"status_changed_ts": "1576283341134105",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166061",
"builder:Linux Builder (dbg)",
"buildset:commit/git/3eada85e379983fdc972bd43200b6881d9c208e7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3eada85e379983fdc972bd43200b6881d9c208e7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059574350800715824",
"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/8894144287504835808/+/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:491a6e9679d7ec10",
"user_agent:luci-scheduler"
],
"updated_ts": "1576283341140527",
"url": "https://ci.chromium.org/b/8894144287504835808",
"utcnow_ts": "1576283341927699"
}
}
[00:29:01.938] Invocation finished in 54m55.661445188s with status SUCCEEDED