[15:34:08.215] New invocation is queued and will start shortly
[15:34:09.402] Starting the invocation (attempt 1)
[15:34:09.437] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:34:09.437] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:34:09.437] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019651285590406992",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"01ac9494fea8b727b6e73b8a9c04e0093e90fe24\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9019651285590406992",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/01ac9494fea8b727b6e73b8a9c04e0093e90fe24",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/01ac9494fea8b727b6e73b8a9c04e0093e90fe24",
"gitiles_ref:refs/heads/master"
]
}
[15:34:10.142] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614353649534288",
"id": "8854221223544704336",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"01ac9494fea8b727b6e73b8a9c04e0093e90fe24\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614353650016424",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/180960",
"builder:Linux Builder (dbg)",
"buildset:commit/git/01ac9494fea8b727b6e73b8a9c04e0093e90fe24",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/01ac9494fea8b727b6e73b8a9c04e0093e90fe24",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019651285590406992",
"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/8854221223544704336/+/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": "1614353650016529",
"url": "https://ci.chromium.org/b/8854221223544704336",
"utcnow_ts": "1614353650136018"
}
}
[15:34:10.142] Task URL: https://ci.chromium.org/b/8854221223544704336
[15:34:10.142] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:2:0) after 1m0s
[15:34:12.772] Received PubSub notification, asking Buildbucket for the build status
[15:34:12.883] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:10.164] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:2:0)
[15:35:10.164] Timer tick, asking Buildbucket for the build status
[15:35:10.238] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:10.238] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:4:0) after 1m0s
[15:36:10.275] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:4:0)
[15:36:10.275] Timer tick, asking Buildbucket for the build status
[15:36:10.406] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:10.406] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:5:0) after 1m0s
[15:37:10.425] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:5:0)
[15:37:10.425] Timer tick, asking Buildbucket for the build status
[15:37:10.522] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:10.522] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:6:0) after 1m0s
[15:38:10.521] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:6:0)
[15:38:10.521] Timer tick, asking Buildbucket for the build status
[15:38:10.603] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:10.603] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:7:0) after 1m0s
[15:39:10.690] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:7:0)
[15:39:10.690] Timer tick, asking Buildbucket for the build status
[15:39:10.769] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:10.769] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:8:0) after 1m0s
[15:40:10.823] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:8:0)
[15:40:10.823] Timer tick, asking Buildbucket for the build status
[15:40:10.959] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:10.959] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:9:0) after 1m0s
[15:41:10.981] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:9:0)
[15:41:10.981] Timer tick, asking Buildbucket for the build status
[15:41:11.079] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:11.079] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:10:0) after 1m0s
[15:42:11.093] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:10:0)
[15:42:11.093] Timer tick, asking Buildbucket for the build status
[15:42:11.238] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:11.238] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:11:0) after 1m0s
[15:43:11.276] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:11:0)
[15:43:11.276] Timer tick, asking Buildbucket for the build status
[15:43:11.502] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:11.502] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:12:0) after 1m0s
[15:44:11.620] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:12:0)
[15:44:11.620] Timer tick, asking Buildbucket for the build status
[15:44:11.719] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:11.719] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:13:0) after 1m0s
[15:45:11.774] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:13:0)
[15:45:11.774] Timer tick, asking Buildbucket for the build status
[15:45:12.111] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:12.111] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:14:0) after 1m0s
[15:46:12.189] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:14:0)
[15:46:12.189] Timer tick, asking Buildbucket for the build status
[15:46:12.304] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:12.304] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:15:0) after 1m0s
[15:47:12.338] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:15:0)
[15:47:12.338] Timer tick, asking Buildbucket for the build status
[15:47:12.408] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:12.408] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:16:0) after 1m0s
[15:48:12.436] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:16:0)
[15:48:12.436] Timer tick, asking Buildbucket for the build status
[15:48:12.506] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:12.507] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:17:0) after 1m0s
[15:49:12.800] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:17:0)
[15:49:12.800] Timer tick, asking Buildbucket for the build status
[15:49:12.862] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:12.862] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:18:0) after 1m0s
[15:50:13.059] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:18:0)
[15:50:13.059] Timer tick, asking Buildbucket for the build status
[15:50:13.143] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:13.143] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:19:0) after 1m0s
[15:51:13.238] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:19:0)
[15:51:13.238] Timer tick, asking Buildbucket for the build status
[15:51:13.355] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:13.355] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:20:0) after 1m0s
[15:52:13.397] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:20:0)
[15:52:13.397] Timer tick, asking Buildbucket for the build status
[15:52:13.734] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:13.734] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:21:0) after 1m0s
[15:53:13.885] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:21:0)
[15:53:13.885] Timer tick, asking Buildbucket for the build status
[15:53:14.199] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:14.200] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:22:0) after 1m0s
[15:54:14.286] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:22:0)
[15:54:14.287] Timer tick, asking Buildbucket for the build status
[15:54:14.388] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:14.388] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:23:0) after 1m0s
[15:55:14.429] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:23:0)
[15:55:14.429] Timer tick, asking Buildbucket for the build status
[15:55:14.517] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:14.517] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:24:0) after 1m0s
[15:56:14.574] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:24:0)
[15:56:14.574] Timer tick, asking Buildbucket for the build status
[15:56:14.800] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:14.800] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:25:0) after 1m0s
[15:57:14.966] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:25:0)
[15:57:14.966] Timer tick, asking Buildbucket for the build status
[15:57:15.046] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:15.046] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:26:0) after 1m0s
[15:58:15.033] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:26:0)
[15:58:15.033] Timer tick, asking Buildbucket for the build status
[15:58:15.234] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:15.234] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:27:0) after 1m0s
[15:59:15.279] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:27:0)
[15:59:15.279] Timer tick, asking Buildbucket for the build status
[15:59:15.355] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:15.355] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:28:0) after 1m0s
[16:00:15.470] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:28:0)
[16:00:15.470] Timer tick, asking Buildbucket for the build status
[16:00:15.614] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:15.614] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:29:0) after 1m0s
[16:01:15.668] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:29:0)
[16:01:15.668] Timer tick, asking Buildbucket for the build status
[16:01:15.915] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:15.915] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:30:0) after 1m0s
[16:02:15.925] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:30:0)
[16:02:15.925] Timer tick, asking Buildbucket for the build status
[16:02:16.018] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:16.018] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:31:0) after 1m0s
[16:03:16.104] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:31:0)
[16:03:16.104] Timer tick, asking Buildbucket for the build status
[16:03:16.171] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:16.171] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:32:0) after 1m0s
[16:04:16.248] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:32:0)
[16:04:16.248] Timer tick, asking Buildbucket for the build status
[16:04:16.332] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:16.332] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:33:0) after 1m0s
[16:05:16.379] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:33:0)
[16:05:16.379] Timer tick, asking Buildbucket for the build status
[16:05:16.508] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:16.508] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:34:0) after 1m0s
[16:06:16.604] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:34:0)
[16:06:16.604] Timer tick, asking Buildbucket for the build status
[16:06:16.673] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:16.673] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:35:0) after 1m0s
[16:07:16.732] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:35:0)
[16:07:16.732] Timer tick, asking Buildbucket for the build status
[16:07:16.919] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:16.919] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:36:0) after 1m0s
[16:08:16.984] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:36:0)
[16:08:16.984] Timer tick, asking Buildbucket for the build status
[16:08:17.174] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:17.174] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:37:0) after 1m0s
[16:09:17.194] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:37:0)
[16:09:17.194] Timer tick, asking Buildbucket for the build status
[16:09:17.288] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:17.288] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:38:0) after 1m0s
[16:10:17.310] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:38:0)
[16:10:17.310] Timer tick, asking Buildbucket for the build status
[16:10:17.435] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:17.435] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:39:0) after 1m0s
[16:11:17.452] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:39:0)
[16:11:17.452] Timer tick, asking Buildbucket for the build status
[16:11:17.552] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:17.552] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:40:0) after 1m0s
[16:12:17.607] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:40:0)
[16:12:17.607] Timer tick, asking Buildbucket for the build status
[16:12:17.926] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:17.926] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:41:0) after 1m0s
[16:13:18.117] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:41:0)
[16:13:18.117] Timer tick, asking Buildbucket for the build status
[16:13:26.336] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:26.336] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:42:0) after 1m0s
[16:14:26.353] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:42:0)
[16:14:26.353] Timer tick, asking Buildbucket for the build status
[16:14:26.758] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:26.758] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:43:0) after 1m0s
[16:15:26.760] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:43:0)
[16:15:26.760] Timer tick, asking Buildbucket for the build status
[16:15:26.953] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:26.953] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:44:0) after 1m0s
[16:16:27.020] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:44:0)
[16:16:27.020] Timer tick, asking Buildbucket for the build status
[16:16:27.087] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:27.087] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:45:0) after 1m0s
[16:17:27.128] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:45:0)
[16:17:27.128] Timer tick, asking Buildbucket for the build status
[16:17:27.219] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:27.219] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:46:0) after 1m0s
[16:18:27.205] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:46:0)
[16:18:27.205] Timer tick, asking Buildbucket for the build status
[16:18:27.528] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:27.528] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:47:0) after 1m0s
[16:19:27.592] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:47:0)
[16:19:27.592] Timer tick, asking Buildbucket for the build status
[16:19:27.698] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:27.698] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:48:0) after 1m0s
[16:20:27.745] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:48:0)
[16:20:27.745] Timer tick, asking Buildbucket for the build status
[16:20:27.813] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:27.813] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:49:0) after 1m0s
[16:21:27.855] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:49:0)
[16:21:27.855] Timer tick, asking Buildbucket for the build status
[16:21:28.033] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:28.033] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:50:0) after 1m0s
[16:22:28.101] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:50:0)
[16:22:28.101] Timer tick, asking Buildbucket for the build status
[16:22:28.767] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:28.767] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:51:0) after 1m0s
[16:23:28.792] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:51:0)
[16:23:28.792] Timer tick, asking Buildbucket for the build status
[16:23:30.453] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:30.453] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:52:0) after 1m0s
[16:24:30.535] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:52:0)
[16:24:30.535] Timer tick, asking Buildbucket for the build status
[16:24:30.617] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:30.617] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:53:0) after 1m0s
[16:25:30.707] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:53:0)
[16:25:30.707] Timer tick, asking Buildbucket for the build status
[16:25:30.786] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:30.786] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:54:0) after 1m0s
[16:26:30.810] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:54:0)
[16:26:30.810] Timer tick, asking Buildbucket for the build status
[16:26:33.146] Build 8854221223544704336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:33.146] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019651285590406992:55:0) after 1m0s
[16:27:06.281] Received PubSub notification, asking Buildbucket for the build status
[16:27:06.446] Build 8854221223544704336: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:27:06.446] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614356825087896",
"created_by": "project:chromium",
"created_ts": "1614353649534288",
"id": "8854221223544704336",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"01ac9494fea8b727b6e73b8a9c04e0093e90fe24\"}}",
"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\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"01ac9494fea8b727b6e73b8a9c04e0093e90fe24\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-154-073h\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.linux\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 180960, \"got_angle_revision\": \"23462996035db5276d7afc60fd023d95ddc5e996\", \"got_dawn_revision\": \"6cbef93c73ceff40010e74533d461f80e2afbd51\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"01ac9494fea8b727b6e73b8a9c04e0093e90fe24\", \"got_revision_cp\": \"refs/heads/master@{#858123}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"349abccec8f7ebd1259de11965d58790a76b0ba4\", \"got_v8_revision\": \"bd0108b4c88e0d6f2350cb79b5f363fbd02f3eb7\", \"got_v8_revision_cp\": \"refs/heads/9.0.257@{#1}\", \"got_webrtc_revision\": \"376cf384ac689eca0bf8d4d1d3a3cf80888d3fbb\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33348}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.linux:linux-clang-tidy-dbg\", \"tryserver.chromium.linux:linux_chromium_dbg_ng\", \"tryserver.chromium.linux:linux_chromium_compile_dbg_ng\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"01ac9494fea8b727b6e73b8a9c04e0093e90fe24\", \"swarm_hashes_refs/heads/master(at){#858123}_without_patch\": {\"absl_hardening_tests\": \"1b0ea9bece6e94497624dc4470eda6ed34ab6165\", \"accessibility_unittests\": \"261b369ff9b3e90035ffd883dd638438e33caf38\", \"angle_unittests\": \"1ceb9fee5978680e15696355e14894d9dcf366f5\", \"app_shell_unittests\": \"985ce15f93bd74ffa8815d25c8c8b7a39c1cc672\", \"aura_unittests\": \"99b52a5031cb3b74b0b6da006997f493602e2906\", \"base_unittests\": \"9fc6eeab521479adde110daaad7f5454cfd6b1c2\", \"base_util_unittests\": \"40f8e4e62c7f6a716d89b775c44e918ed4ff86d0\", \"blink_common_unittests\": \"661d2b1d2828431cdb29176d7c63550db193a83a\", \"blink_fuzzer_unittests\": \"c340689d76517a08440dd11abb90dcd280e524ca\", \"blink_heap_unittests\": \"a16489edd4b2df1e3e5bcecc78eceb62f333a3a2\", \"blink_platform_unittests\": \"3630ef651d1a6deffc129cdc3f60678b1b9ad355\", \"blink_python_tests\": \"d18320db10908fe6be4f3366ebe1b58ee50f0a68\", \"blink_unittests\": \"8e2e61a6e3b305e6b9640f17881866be43654f92\", \"blink_web_tests\": \"e62ae6016a01d123c3107bc0ac72a7a21a52ce0e\", \"boringssl_crypto_tests\": \"3b5c516b5423b7e410778cf1ea547df01fbf8d45\", \"boringssl_ssl_tests\": \"8d74d889dfd049fc0a119f856e3b3a10136063e1\", \"browser_tests\": \"d7b36494fe6c34a15a5460813888924a53be491a\", \"capture_unittests\": \"ded816a9473df826bbf05a0d688fc522f485d01f\", \"cast_unittests\": \"347ac2a3f725f973e1b7173b61ee72d79f9a00e7\", \"cc_unittests\": \"e90789255fe6e5ed143444295e101891ac11a07b\", \"chrome_app_unittests\": \"d2d70cf335dd3b151663ca478454fad8aa5f7128\", \"chromedriver_unittests\": \"425374da5f32c85115ad94cfca22d6ae03253f0e\", \"color_unittests\": \"46553c86e4dec7a616fd4b78e0e4572b07aed12e\", \"components_browsertests\": \"6a8509e2fafb43fd88c95fc530930ffc5de7c170\", \"components_unittests\": \"f0f5467a7f6bffbe99ff6482786a2173b581780e\", \"compositor_unittests\": \"35d656038053b39d5dbaa086657d656c299af143\", \"content_browsertests\": \"701eaf09fd9b3e8fda6f6ef5acf4ff70f9e0cdb8\", \"content_shell_crash_test\": \"8f041051b90463e9de0c1543997edb0ac5721f5a\", \"content_unittests\": \"0dfc3a5554f18b9848551e1a27080de677291406\", \"crashpad_tests\": \"65d4e7b7d97f89f53aca70c471c8fa4ad3651853\", \"cronet_tests\": \"6c23d1237c9357d5ad544efe17283f0d094ac07c\", \"cronet_unittests\": \"a5b9df63fe21cd200704b1b879ebfb3af5b334a1\", \"crypto_unittests\": \"b9ca3034e05ae4f7e585bb3a495eb31ded14b227\", \"dbus_unittests\": \"23ccd1089cf868787b1f0f113183badf381f0dc7\", \"device_unittests\": \"52945777ea01cc879746ba17f548ca52bc643143\", \"display_unittests\": \"886e693a9597cc7c2a4eeaafc94f2a4bfae606ff\", \"events_unittests\": \"9849b699545ca434f1d961f69c2cfaff780c5a38\", \"extensions_browsertests\": \"b55f84e4972ae3d69014d01ac62806bb1418a4b3\", \"extensions_unittests\": \"f7a49ac8f07d4035599ed0561386e4e6c1da1748\", \"filesystem_service_unittests\": \"a9c76ff856b0ae8fe095bf5b93c211cbaf7aa876\", \"flatbuffers_unittests\": \"3e3b6c7bf0c1182740cf5cce7088efe0220a77b5\", \"gcm_unit_tests\": \"8963af4b3de1ee30f1115b6415b7e2d026d04dd6\", \"gfx_unittests\": \"353bfdec9d0a5f8f4f60d343a7bec8b2c53f24a5\", \"gin_unittests\": \"d5223ee8e555f6bb19a68a9b002b6cf4f4d9a471\", \"google_apis_unittests\": \"28727c6834e29428d171f458737f373b40745a2b\", \"gpu_unittests\": \"432031c9a186a07e4b49c4c78adbdaf4ed144fca\", \"grit_python_unittests\": \"00738482c42a9310ca0fd96f0709b3d808390403\", \"gwp_asan_unittests\": \"f56161b17e68ba7aeec1a689d93836295851c4aa\", \"headless_browsertests\": \"d3578346a160c06b33f84769747c64940b485fb1\", \"headless_unittests\": \"be96c55a37badba56f8b01a10673f9d8391395a6\", \"interactive_ui_tests\": \"68efa50bc047fd12f0bcae1e18046934aabdf04d\", \"ipc_tests\": \"ffc521968507f431f973c72c2ed77a7c66b2c2c4\", \"jingle_unittests\": \"1a6f1f916bb0b553b388b384151ebf831b964cf2\", \"latency_unittests\": \"4ac43056bd4107f374388223c418580ac6cee02f\", \"libjingle_xmpp_unittests\": \"a99bb7ac1453a7a63959bb15e6b56884c30079b2\", \"liburlpattern_unittests\": \"108a58028d8bd9ae966d8cfa1f6411b25382dc26\", \"media_blink_unittests\": \"9c0bff22ba2d7025e6b6ae09ec6b9a6274bb0c32\", \"media_unittests\": \"45b342fea91af8fca0a065735a703c38c6e385bf\", \"message_center_unittests\": \"044bfd595bc5634ea59d3d7c78692550aa17593f\", \"metrics_python_tests\": \"9fd02446674068b4b0b66e33eb19d8bfb70a021c\", \"midi_unittests\": \"4daa30c653b17e98ef0a9f8e0ae8397cf359b3ca\", \"mojo_core_unittests\": \"0353bb91d4c732df9f455569e6608f375e592aef\", \"mojo_unittests\": \"28307b3c870e24e6520a2b5ed1d7846660c96387\", \"nacl_helper_nonsfi_unittests\": \"c3abb425c8ca883df3459f373499a8e20f11874d\", \"nacl_loader_unittests\": \"35432c0d8b45db9211e4309961106a454b2d2c30\", \"native_theme_unittests\": \"014f324fe3a5650b2bb6575828bc57b5aaf46ec7\", \"net_unittests\": \"f509a365585e8616a0d5e93d6c5ffaa03fadd167\", \"openscreen_unittests\": \"008d3c9627f39925373464565257cb8a5425fa12\", \"pdf_unittests\": \"86ad89441f42fabf6b0151a4a3a34a57686c9c72\", \"perfetto_unittests\": \"aff24ad4a05bca25662b9de8f055c39bd7615934\", \"ppapi_unittests\": \"d84f4cb9163708a80d459617cfd56e022a5e6b7d\", \"printing_unittests\": \"ecaf709e7da1686118dbc408c8b9ab5a652864fa\", \"remoting_unittests\": \"db36054c841f4a7417c99ef778baff1e95141f8e\", \"sandbox_linux_unittests\": \"8eea1a8490ac443e34d056fb2bd7e9c91328fbb5\", \"service_manager_unittests\": \"ea4859c372fc418410384d2ccfde67de6b8a104a\", \"services_unittests\": \"e933e820794c96120d4c6ebc4a53b774b9c2e4fe\", \"shell_dialogs_unittests\": \"69441c829ce2809d1b52dccb5f5f5bada72a6d3a\", \"skia_unittests\": \"5006307e1494c9cab90b6470504d7f0c62f69d1c\", \"snapshot_unittests\": \"9cf3f47bee36b2ca35eeac8f095ad1f604b30ddd\", \"sql_unittests\": \"5b8884c778f7dfc209f98c53040be6e2b49822ad\", \"storage_unittests\": \"a0d0e6af00aff3df68c5a556fc96e70d765c7f14\", \"sync_integration_tests\": \"01ff239239ce242eddb7c33fc3cb93864e984a8b\", \"telemetry_gpu_unittests\": \"d0edeff261e359c29bcbb17218c88caf3a063bee\", \"telemetry_perf_unittests\": \"ac7c194e6c6ac32c2b45b3d1f1b612d72395a334\", \"telemetry_unittests\": \"780393029d6ea34de2e09fb04db19dc6f83daa77\", \"traffic_annotation_auditor_unittests\": \"6d03110ccb0afcda211b622fea5b3d0b142d378c\", \"ui_base_unittests\": \"8b15f1a67a16d7bfd8ed2884f33256ff6431d21e\", \"ui_touch_selection_unittests\": \"41a673696fd3333582449996f39eb4394bff573c\", \"unit_tests\": \"ee3f88d2f4a82f2de1eec3db06569f7de8674001\", \"url_unittests\": \"f0abb33ddf2e7d60ae63896906b0763f68dcf433\", \"views_perftests\": \"dc820f394ea43ea4235f3f3fed1cdf0103262c5a\", \"views_unittests\": \"89b3673b9c9cdb182cd388a2001f19fd6e046da1\", \"viz_unittests\": \"eda06b6bcab75236c584886aa2072a21bccc60b1\", \"vr_common_unittests\": \"c2eabb943ec403c8fce21ad374ba7d26aff2fba4\", \"vr_pixeltests\": \"452caf29cf22cb620797c5ef89ddde21b7e576ba\", \"webdriver_wpt_tests\": \"a4367a1f85518e9d7d58822160bd024f9b263632\", \"weblayer_browsertests\": \"04542ae8c6b82b52664fc0e4c63e0c84df9410cb\", \"weblayer_unittests\": \"1046ece257f13f8faa4e0d59768a73973baf682f\", \"wm_unittests\": \"a3cb60a178572b34ce2732312406061b2f1ca306\", \"wtf_unittests\": \"2e65e9d872dde94e3116e7863a3553460174d732\", \"x11_unittests\": \"8931b06621a0b131ef9c6b50fdf8f6cbb8882425\", \"xr_browser_tests\": \"ea6aea4918dedf82ca383f619516af505ce2b60d\", \"zlib_unittests\": \"1ab21176f9811759b7f9070a8b7cdf532dc59ecd\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_51829829c31fbaf3768e3309e640f1ed37e0867e138bde8c875431bcab07adc2_v2\", \"builder_5f931a5519f78feef046af549827aa56dc2e9b17853b60b3d0ae8ebd533780ee_v2\", \"builder_5f9879dd307b3e3de87d4273aaf77dfc3adf50a63c1d75c3b245bfdff463a933_v2\", \"builder_609b54f795c46f6da98bce3e3c3edeb2c2e2d72c7759cc5040a86edccd734c7c_v2\", \"builder_821d31345f542667479b2b218f49698206c649e4692b986670b0d93c55460459_v2\", \"builder_e21c17032b725bcf1c922976a36352546baf3f39ec148d1e6730a93c0400d8cc_v2\", \"builder_ea4e7feff896b82ff6619a3b9c5371d5e74ebdd508befd295e2f5ab02f5eefaf_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-154-073h\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5712-339372b\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614353652130449",
"status": "COMPLETED",
"status_changed_ts": "1614356825422315",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/180960",
"builder:Linux Builder (dbg)",
"buildset:commit/git/01ac9494fea8b727b6e73b8a9c04e0093e90fe24",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/01ac9494fea8b727b6e73b8a9c04e0093e90fe24",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019651285590406992",
"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/8854221223544704336/+/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:51f7cb5b00199710",
"user_agent:luci-scheduler"
],
"updated_ts": "1614356825428025",
"url": "https://ci.chromium.org/b/8854221223544704336",
"utcnow_ts": "1614356826437536"
}
}
[16:27:06.446] Invocation finished in 52m58.246561077s with status SUCCEEDED