[18:10:16.487] New invocation is queued and will start shortly
[18:10:17.933] Starting the invocation (attempt 1)
[18:10:17.971] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:10:17.971] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:10:17.971] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9050353833857031792",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4b9af61883fe218b8b68fb1ca7f70325de1b6aa6\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9050353833857031792",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/4b9af61883fe218b8b68fb1ca7f70325de1b6aa6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4b9af61883fe218b8b68fb1ca7f70325de1b6aa6",
"gitiles_ref:refs/heads/master"
]
}
[18:10:19.659] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1585073418055739",
"id": "8884923771547827248",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4b9af61883fe218b8b68fb1ca7f70325de1b6aa6\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1585073418521669",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/170131",
"builder:Linux Builder (dbg)",
"buildset:commit/git/4b9af61883fe218b8b68fb1ca7f70325de1b6aa6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4b9af61883fe218b8b68fb1ca7f70325de1b6aa6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9050353833857031792",
"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/8884923771547827248/+/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": "1585073418521818",
"url": "https://ci.chromium.org/b/8884923771547827248",
"utcnow_ts": "1585073419652593"
}
}
[18:10:19.659] Task URL: https://ci.chromium.org/b/8884923771547827248
[18:10:19.659] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:2:0) after 1m0s
[18:11:19.769] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:2:0)
[18:11:19.769] Timer tick, asking Buildbucket for the build status
[18:11:19.924] Build 8884923771547827248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:11:19.924] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:3:0) after 1m0s
[18:12:19.957] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:3:0)
[18:12:19.957] Timer tick, asking Buildbucket for the build status
[18:12:20.128] Build 8884923771547827248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:12:20.128] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:4:0) after 1m0s
[18:13:20.247] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:4:0)
[18:13:20.247] Timer tick, asking Buildbucket for the build status
[18:13:20.550] Build 8884923771547827248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:13:20.550] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:5:0) after 1m0s
[18:14:20.607] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:5:0)
[18:14:20.607] Timer tick, asking Buildbucket for the build status
[18:14:20.805] Build 8884923771547827248: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:14:20.805] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:6:0) after 1m0s
[18:15:08.755] Received PubSub notification, asking Buildbucket for the build status
[18:15:08.885] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:20.864] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:6:0)
[18:15:20.864] Timer tick, asking Buildbucket for the build status
[18:15:20.944] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:20.944] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:8:0) after 1m0s
[18:16:21.021] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:8:0)
[18:16:21.021] Timer tick, asking Buildbucket for the build status
[18:16:21.187] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:21.187] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:9:0) after 1m0s
[18:17:21.252] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:9:0)
[18:17:21.252] Timer tick, asking Buildbucket for the build status
[18:17:21.349] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:21.349] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:10:0) after 1m0s
[18:18:21.486] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:10:0)
[18:18:21.486] Timer tick, asking Buildbucket for the build status
[18:18:21.637] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:21.637] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:11:0) after 1m0s
[18:19:21.652] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:11:0)
[18:19:21.652] Timer tick, asking Buildbucket for the build status
[18:19:21.872] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:21.872] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:12:0) after 1m0s
[18:20:22.035] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:12:0)
[18:20:22.035] Timer tick, asking Buildbucket for the build status
[18:20:22.140] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:22.140] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:13:0) after 1m0s
[18:21:22.939] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:13:0)
[18:21:22.939] Timer tick, asking Buildbucket for the build status
[18:21:23.018] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:23.018] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:14:0) after 1m0s
[18:22:23.123] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:14:0)
[18:22:23.123] Timer tick, asking Buildbucket for the build status
[18:22:23.253] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:23.253] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:15:0) after 1m0s
[18:23:23.252] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:15:0)
[18:23:23.252] Timer tick, asking Buildbucket for the build status
[18:23:23.440] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:23.440] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:16:0) after 1m0s
[18:24:23.461] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:16:0)
[18:24:23.461] Timer tick, asking Buildbucket for the build status
[18:24:23.685] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:23.685] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:17:0) after 1m0s
[18:25:23.735] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:17:0)
[18:25:23.735] Timer tick, asking Buildbucket for the build status
[18:25:23.911] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:23.911] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:18:0) after 1m0s
[18:26:23.968] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:18:0)
[18:26:23.968] Timer tick, asking Buildbucket for the build status
[18:26:24.106] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:24.106] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:19:0) after 1m0s
[18:27:24.158] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:19:0)
[18:27:24.158] Timer tick, asking Buildbucket for the build status
[18:27:24.597] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:24.597] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:20:0) after 1m0s
[18:28:24.667] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:20:0)
[18:28:24.667] Timer tick, asking Buildbucket for the build status
[18:28:24.728] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:24.728] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:21:0) after 1m0s
[18:29:24.818] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:21:0)
[18:29:24.818] Timer tick, asking Buildbucket for the build status
[18:29:25.152] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:25.152] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:22:0) after 1m0s
[18:30:25.174] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:22:0)
[18:30:25.174] Timer tick, asking Buildbucket for the build status
[18:30:25.349] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:25.349] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:23:0) after 1m0s
[18:31:25.450] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:23:0)
[18:31:25.450] Timer tick, asking Buildbucket for the build status
[18:31:25.559] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:25.559] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:24:0) after 1m0s
[18:32:25.677] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:24:0)
[18:32:25.677] Timer tick, asking Buildbucket for the build status
[18:32:25.743] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:25.743] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:25:0) after 1m0s
[18:33:25.852] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:25:0)
[18:33:25.852] Timer tick, asking Buildbucket for the build status
[18:33:26.056] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:26.056] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:26:0) after 1m0s
[18:34:26.075] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:26:0)
[18:34:26.075] Timer tick, asking Buildbucket for the build status
[18:34:26.234] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:26.234] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:27:0) after 1m0s
[18:35:26.266] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:27:0)
[18:35:26.266] Timer tick, asking Buildbucket for the build status
[18:35:26.384] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:26.384] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:28:0) after 1m0s
[18:36:26.456] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:28:0)
[18:36:26.456] Timer tick, asking Buildbucket for the build status
[18:36:26.555] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:26.555] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:29:0) after 1m0s
[18:37:26.660] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:29:0)
[18:37:26.660] Timer tick, asking Buildbucket for the build status
[18:37:26.814] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:26.814] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:30:0) after 1m0s
[18:38:26.891] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:30:0)
[18:38:26.891] Timer tick, asking Buildbucket for the build status
[18:38:27.470] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:27.470] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:31:0) after 1m0s
[18:39:27.584] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:31:0)
[18:39:27.584] Timer tick, asking Buildbucket for the build status
[18:39:27.763] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:27.763] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:32:0) after 1m0s
[18:40:27.796] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:32:0)
[18:40:27.796] Timer tick, asking Buildbucket for the build status
[18:40:27.857] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:27.857] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:33:0) after 1m0s
[18:41:28.019] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:33:0)
[18:41:28.019] Timer tick, asking Buildbucket for the build status
[18:41:28.172] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:28.173] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:34:0) after 1m0s
[18:42:28.302] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:34:0)
[18:42:28.302] Timer tick, asking Buildbucket for the build status
[18:42:28.449] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:28.449] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:35:0) after 1m0s
[18:43:28.529] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:35:0)
[18:43:28.529] Timer tick, asking Buildbucket for the build status
[18:43:28.694] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:28.694] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:36:0) after 1m0s
[18:44:28.719] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:36:0)
[18:44:28.719] Timer tick, asking Buildbucket for the build status
[18:44:28.877] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:28.877] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:37:0) after 1m0s
[18:45:28.966] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:37:0)
[18:45:28.966] Timer tick, asking Buildbucket for the build status
[18:45:29.398] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:29.398] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:38:0) after 1m0s
[18:46:29.447] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:38:0)
[18:46:29.447] Timer tick, asking Buildbucket for the build status
[18:46:29.622] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:29.622] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:39:0) after 1m0s
[18:47:29.705] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:39:0)
[18:47:29.705] Timer tick, asking Buildbucket for the build status
[18:47:29.833] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:29.833] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:40:0) after 1m0s
[18:48:29.852] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:40:0)
[18:48:29.852] Timer tick, asking Buildbucket for the build status
[18:48:30.151] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:30.151] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:41:0) after 1m0s
[18:49:30.253] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:41:0)
[18:49:30.253] Timer tick, asking Buildbucket for the build status
[18:49:30.379] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:30.379] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:42:0) after 1m0s
[18:50:30.383] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:42:0)
[18:50:30.383] Timer tick, asking Buildbucket for the build status
[18:50:30.546] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:30.546] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:43:0) after 1m0s
[18:51:30.591] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:43:0)
[18:51:30.591] Timer tick, asking Buildbucket for the build status
[18:51:30.782] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:30.782] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:44:0) after 1m0s
[18:52:30.898] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:44:0)
[18:52:30.898] Timer tick, asking Buildbucket for the build status
[18:52:30.982] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:30.982] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:45:0) after 1m0s
[18:53:31.124] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:45:0)
[18:53:31.124] Timer tick, asking Buildbucket for the build status
[18:53:31.269] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:31.269] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:46:0) after 1m0s
[18:54:31.290] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:46:0)
[18:54:31.290] Timer tick, asking Buildbucket for the build status
[18:54:31.445] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:31.446] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:47:0) after 1m0s
[18:55:31.480] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:47:0)
[18:55:31.480] Timer tick, asking Buildbucket for the build status
[18:55:31.714] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:31.714] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:48:0) after 1m0s
[18:56:31.752] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:48:0)
[18:56:31.752] Timer tick, asking Buildbucket for the build status
[18:56:31.819] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:31.819] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:49:0) after 1m0s
[18:57:31.930] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:49:0)
[18:57:31.930] Timer tick, asking Buildbucket for the build status
[18:57:32.343] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:32.343] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:50:0) after 1m0s
[18:58:32.429] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:50:0)
[18:58:32.429] Timer tick, asking Buildbucket for the build status
[18:58:33.098] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:33.098] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:51:0) after 1m0s
[18:59:33.122] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:51:0)
[18:59:33.122] Timer tick, asking Buildbucket for the build status
[18:59:33.281] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:33.281] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:52:0) after 1m0s
[19:00:33.300] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:52:0)
[19:00:33.300] Timer tick, asking Buildbucket for the build status
[19:00:33.509] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:33.509] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:53:0) after 1m0s
[19:01:33.532] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:53:0)
[19:01:33.532] Timer tick, asking Buildbucket for the build status
[19:01:33.654] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:33.654] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:54:0) after 1m0s
[19:02:33.728] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:54:0)
[19:02:33.728] Timer tick, asking Buildbucket for the build status
[19:02:34.083] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:34.083] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:55:0) after 1m0s
[19:03:34.147] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:55:0)
[19:03:34.147] Timer tick, asking Buildbucket for the build status
[19:03:34.751] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:34.751] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:56:0) after 1m0s
[19:04:34.907] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:56:0)
[19:04:34.907] Timer tick, asking Buildbucket for the build status
[19:04:34.972] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:34.972] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:57:0) after 1m0s
[19:05:35.062] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:57:0)
[19:05:35.062] Timer tick, asking Buildbucket for the build status
[19:05:35.315] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:35.315] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:58:0) after 1m0s
[19:06:35.515] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:58:0)
[19:06:35.515] Timer tick, asking Buildbucket for the build status
[19:06:35.663] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:35.663] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:59:0) after 1m0s
[19:07:35.737] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:59:0)
[19:07:35.737] Timer tick, asking Buildbucket for the build status
[19:07:36.567] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:36.567] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:60:0) after 1m0s
[19:08:36.685] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:60:0)
[19:08:36.685] Timer tick, asking Buildbucket for the build status
[19:08:37.044] Build 8884923771547827248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:37.044] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9050353833857031792:61:0) after 1m0s
[19:09:16.470] Received PubSub notification, asking Buildbucket for the build status
[19:09:16.818] Build 8884923771547827248: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:09:16.818] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1585076955235231",
"created_by": "project:chromium",
"created_ts": "1585073418055739",
"id": "8884923771547827248",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4b9af61883fe218b8b68fb1ca7f70325de1b6aa6\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/chromium_tests\": {\"bucketed_triggers\": true}, \"$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-east1-d-8-62-gnfo\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 170131, \"got_angle_revision\": \"ed0e932cc0b77cdcf42b8d1d9287dfff82321b4f\", \"got_dawn_revision\": \"11652ff8f8b3c3104eb2627717fa652d432d5b84\", \"got_nacl_revision\": \"ffed96d1528df9f114a30b14d4eb06120c8fb022\", \"got_revision\": \"4b9af61883fe218b8b68fb1ca7f70325de1b6aa6\", \"got_revision_cp\": \"refs/heads/master@{#752920}\", \"got_swarming_client_revision\": \"cc958279ffd6853e0a1b227a7e957ca334fe56af\", \"got_swiftshader_revision\": \"540bdf92531d0c325d7b5c770ec33e869b56059f\", \"got_v8_revision\": \"1085ec5a7c9a6040387a59ec8a02a87fafac8ff3\", \"got_v8_revision_cp\": \"refs/heads/8.3.65@{#1}\", \"got_webrtc_revision\": \"865a22d6bb3db73eb2a4f16d419591eeb49330c5\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30864}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4b9af61883fe218b8b68fb1ca7f70325de1b6aa6\", \"swarm_hashes_refs/heads/master(at){#752920}_without_patch\": {\"accessibility_unittests\": \"1c1eb73893331defb0bcf3c9c8981a7cfdf9df58\", \"angle_unittests\": \"3669cfb160687c72c1e6026a7d746f8cc1feda22\", \"app_shell_unittests\": \"8b817eb8fb473b12a11c980739b7ddb95ea733f4\", \"aura_unittests\": \"bded377f6aeb98ffa352a9debb6e1319878e1163\", \"base_unittests\": \"7e85109454319c146731adcf3b913670d31d8daf\", \"base_util_unittests\": \"c630da89102e1a96646e6967a099f79c7486af2f\", \"blink_common_unittests\": \"80fc0e8443b4c96337877cdd1599c367f54f3f4f\", \"blink_fuzzer_unittests\": \"93b60742beeb206405bc3aad97a740d03606f46f\", \"blink_heap_unittests\": \"1341ad86b0c71ab7249c05df3c404659918def02\", \"blink_platform_unittests\": \"295ac77fd52eb724dc38dfe72534f0b9a7f7bc60\", \"blink_python_tests\": \"ee2794b22e2ed4f6de8b1daa58c134bdf59b2a16\", \"blink_unittests\": \"fce1b8a7e1c23d6397735a70cc14d6bfb8e16f16\", \"blink_web_tests\": \"2deebd1cd824c3d4a4281fd29423ecb4c1b01ee4\", \"boringssl_crypto_tests\": \"3db8d6e68477168715720cb58893ba39c2e1fa64\", \"boringssl_ssl_tests\": \"038e1fd263eae144b1f795c9fa4b9c93124d4b39\", \"browser_tests\": \"2660e2b5b89e388ca9346ef41054ba7950a1990f\", \"cacheinvalidation_unittests\": \"e3ca3bddd423729fbef5ec955b158c9321219edc\", \"capture_unittests\": \"93a4bc6f6225ed4ce6b1259343f5bc0c58f09ead\", \"cast_unittests\": \"89a0c38ecd20a00743272be00282b7ea5daf223a\", \"cc_unittests\": \"ca8bfe2fd91af7df310c2dc3e2b9e8320817fe47\", \"chrome_app_unittests\": \"f7240ead8aa2862c6b06150d35ea0bbc70a6081e\", \"chromedriver_unittests\": \"db775587f2c8e376b6733686d9938f3429283d26\", \"components_browsertests\": \"4c8254f5115d0b35172a227cc494a0e66c2657be\", \"components_unittests\": \"4d60e7295306bc26f5950025ed23e312442c5e64\", \"compositor_unittests\": \"e91e0b5e3363c81c86a7ef5983170f7f32b4a824\", \"content_browsertests\": \"99c7c0401e9271ba7b980e767fc4cd528f3c4247\", \"content_shell_crash_test\": \"3078a3b4866347a14f59ead29cf5e95fef7f9067\", \"content_unittests\": \"668a33bfcb7bed3569d4d327e6ce2739425da375\", \"crashpad_tests\": \"2627d151bf91f5aa24aa87cfabb02f5932baee65\", \"cronet_tests\": \"0395aa6271cf8cfb223bbe6a0c6c28b10eb5af81\", \"cronet_unittests\": \"a0984573a3d3a569ad618c200c89790bfed5495d\", \"crypto_unittests\": \"8b2644f8cb62fde3b6dd7f5acd9a4239f491812e\", \"dbus_unittests\": \"b501d522fb3a408f8a7ca1cecfce648d92bebac7\", \"device_unittests\": \"617db9c6ed4235f1772d3337cbe6a5443104d4a8\", \"display_unittests\": \"7072a2dbb32572699642c2618d9286424daeea85\", \"events_unittests\": \"725da710207ca4b6280e294710a9f4f7d2f9cd2d\", \"extensions_browsertests\": \"72d66032cbe5c516987663d0959e76ea5d0de7da\", \"extensions_unittests\": \"74d54b7275952a3f4f3e54dc61fced9828e7de26\", \"filesystem_service_unittests\": \"8853a8932e7fe73ac376704c1fe38a5013044e8e\", \"flatbuffers_unittests\": \"50e47be8364b1aba201300b5e504c8137bc62062\", \"gcm_unit_tests\": \"1ed315f51c80535a1551c46026959f4988a1db7f\", \"gfx_unittests\": \"e92fe19c314197dd1e819a510f6b51e0852295f2\", \"gin_unittests\": \"fb57c21147116716b6fdcb3396a94c42eb2961c3\", \"google_apis_unittests\": \"ffee7aefdeea6b6f2118ae1fe891b31368374ae4\", \"gpu_unittests\": \"9bbc8c2d34716aa0fa42f6cbfeee27e7011264a2\", \"grit_python_unittests\": \"afbbe1df7c81809157ec9d2af582b8cb9256fe66\", \"gwp_asan_unittests\": \"bfd9098cb2c6d7f9e1256ca855fa8e254b73b1da\", \"headless_browsertests\": \"6f939cdee2f0652c44748a2db532436ad419bdbb\", \"headless_unittests\": \"b01cb2c1d954d7dcde747af32dd2d087bd51cc81\", \"interactive_ui_tests\": \"50d7c8905fbf1cbae560c392faddc98a73f8c3ba\", \"ipc_tests\": \"006c0f273bc237d505090d851268aab33959b453\", \"jingle_unittests\": \"b4a44051376c5c799289a8cc95e08eb9dd63298e\", \"latency_unittests\": \"cdac30313aaedbc052965d345cb6181c21e6e200\", \"libjingle_xmpp_unittests\": \"8fa14970b66524c47da886e44a2c465ac779010a\", \"media_blink_unittests\": \"07beaa965eb8100d23afb8889bd1e1b93919b0c4\", \"media_service_unittests\": \"91b76d338e15f1de99595dae8f4d2fa5bab73cbe\", \"media_unittests\": \"9dba943fcd6df47ae1c20a0f39afe9af0e6d3836\", \"message_center_unittests\": \"01ccd53f93828aa8e3ef841de3fa47c1f6faa597\", \"metrics_python_tests\": \"43c37db7018fe7b74d7d8dbacd517ecbf0a964a9\", \"midi_unittests\": \"47e65d3c7aec2d2d81a671b288dc9ef6cb87227f\", \"mojo_core_unittests\": \"c72e5d6070d444eacd4b2a6689acba249e1587bf\", \"mojo_unittests\": \"f36db720d39159891db028635ae83fc5dfacf748\", \"nacl_helper_nonsfi_unittests\": \"7923d36b1843f978b5938d08543b79ae590befac\", \"nacl_loader_unittests\": \"916c0146e186319756e68bb4592afd943b408e9f\", \"native_theme_unittests\": \"e45456895a8018dcf610dd6885fe2758a519647b\", \"net_unittests\": \"4fd864e132d7e5d63cdf20e3434dd33e2faa1dc2\", \"pdf_unittests\": \"4f388c241da0c7ea414545cb9bce91860681674e\", \"perfetto_unittests\": \"b628a7e291c513eeed0c8101768e5ece95fe8e1b\", \"ppapi_unittests\": \"cc5ab331c8bca094f01112c7676b76407254f00d\", \"printing_unittests\": \"d2a9ec4c1a0b9c24831aa2483496486153acdc73\", \"remoting_unittests\": \"abec10e610745ae3b6feea9f6165805b90210e47\", \"sandbox_linux_unittests\": \"16f1d8f1f3e928a035259e4fba0b54c83a77ac3e\", \"service_manager_unittests\": \"0c9033f8b7dbf57b7b55c64e555f4de234a0d519\", \"services_unittests\": \"00985dac6b9dc057815cdf1f05570f4ae0261651\", \"shell_dialogs_unittests\": \"89de5383d3c7bfc210672e2080c45022e9d5c5e4\", \"skia_unittests\": \"6debad951312e2546300bf1e50b7e07342b3885e\", \"snapshot_unittests\": \"0e3b8b6184bbe793e07091dc999696ed6cad9b66\", \"sql_unittests\": \"1330eec6ec22062d1849204364b55db13982575e\", \"storage_unittests\": \"c0a364cc75aabe239b41f9aa8b829ec1b6b2063b\", \"sync_integration_tests\": \"19cfa1778a9e07530ad0d74df9ab0f2d9223c090\", \"telemetry_gpu_unittests\": \"41fad54ab9c85250bcd963f5cba08eabe76bff1d\", \"telemetry_perf_unittests\": \"c543c7f44344b88ce769027962d2d1efdc85e886\", \"telemetry_unittests\": \"bef8d52e38c7d4667188a42f8f97a7233825d030\", \"traffic_annotation_auditor_unittests\": \"22e3051717a482bb4027cbe8bc8df77c4f6520ef\", \"ui_base_unittests\": \"52c1502703090c7d467c4f32ce3144945bc605cf\", \"ui_touch_selection_unittests\": \"b39c08f11f51dc73f0abf35f71c8c625900d5e17\", \"unit_tests\": \"9769077124c8cb30f8ef21c09c2a1308b187b71b\", \"url_unittests\": \"7c4403dd96890943565f86af5fab10b33c748976\", \"views_perftests\": \"80434a99a1670144a581161b395946900b6b110e\", \"views_unittests\": \"ef86bf593e1de1385349c79842767860b8db3a08\", \"viz_unittests\": \"e55edd4a7f8995ca16b0f80ca44b9430200fde1d\", \"vr_common_unittests\": \"1dfb37047fc51a888549a0d2582d61431d4c69e0\", \"vr_pixeltests\": \"873f77f7cafef4b2e37dd7c7363c1b4c8d4990c6\", \"webdriver_wpt_tests\": \"ff7a9eba130664c5116ad647f49798abf3689dc0\", \"weblayer_browsertests\": \"dd3963557005ce618474ef4461f14767e52cf1ce\", \"wm_unittests\": \"ea2912eaf1eb5c3cc3d700f42444e89b2f42417b\", \"wtf_unittests\": \"3a09066fa17de58cd27464d53743fe1f29f37336\", \"xr_browser_tests\": \"5f0abcdd4fbeb7ad4780497fe8b546974bb72589\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2295bac873f7edd8c9763e89419d85b38a875955550d968c436d775d3f06377b_v2\", \"builder_45c5b54513fd8d251ddf48af87bd70552077e6f51953669d04eaa4296b96a344_v2\", \"builder_97ff76ae173a9ce5e244c07639f8a3e6b9cacff7958414e43d82a5f30a20f3d2_v2\", \"builder_dd987fcd94b516d81fcf1552cd73a9eed370c3dc02cc1a52ddee828f1ab8817d_v2\", \"builder_e7fd4b7a9e211f8d968af60263c508e13e49814167aff980c2bf239c05ea48ef_v2\", \"builder_eeb4aed1f5957a78d1f0fe1c00bdb778895591d08c1bb63c47ebb234324ef661_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-east1-d-8-62-gnfo\"], \"image\": [\"chrome-xenial-20031500-6b26172a85c\"], \"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\": [\"4895-ce889b2\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1585073706786974",
"status": "COMPLETED",
"status_changed_ts": "1585076955719154",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/170131",
"builder:Linux Builder (dbg)",
"buildset:commit/git/4b9af61883fe218b8b68fb1ca7f70325de1b6aa6",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4b9af61883fe218b8b68fb1ca7f70325de1b6aa6",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9050353833857031792",
"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/8884923771547827248/+/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:4b268ed35c1a8310",
"user_agent:luci-scheduler"
],
"updated_ts": "1585076956029888",
"url": "https://ci.chromium.org/b/8884923771547827248",
"utcnow_ts": "1585076956787249"
}
}
[19:09:16.818] Invocation finished in 59m0.349210719s with status SUCCEEDED