[03:15:07.790] New invocation is queued and will start shortly
[03:15:09.307] Starting the invocation (attempt 1)
[03:15:09.365] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:15:09.365] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:15:09.365] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9057657908828013072",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"8ea17a540f636103c9bba470d1a067d3e10f5777\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9057657908828013072",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/8ea17a540f636103c9bba470d1a067d3e10f5777",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8ea17a540f636103c9bba470d1a067d3e10f5777",
"gitiles_ref:refs/heads/master"
]
}
[03:15:10.662] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1578107710100460",
"id": "8892227845732701808",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8ea17a540f636103c9bba470d1a067d3e10f5777\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1578107710511234",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166949",
"builder:Linux Builder (dbg)",
"buildset:commit/git/8ea17a540f636103c9bba470d1a067d3e10f5777",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8ea17a540f636103c9bba470d1a067d3e10f5777",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9057657908828013072",
"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/8892227845732701808/+/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": "1578107710511341",
"url": "https://ci.chromium.org/b/8892227845732701808",
"utcnow_ts": "1578107710642137"
}
}
[03:15:10.662] Task URL: https://ci.chromium.org/b/8892227845732701808
[03:15:10.662] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:2:0) after 1m0s
[03:16:10.700] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:2:0)
[03:16:10.700] Timer tick, asking Buildbucket for the build status
[03:16:11.042] Build 8892227845732701808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:16:11.042] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:3:0) after 1m0s
[03:17:11.059] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:3:0)
[03:17:11.059] Timer tick, asking Buildbucket for the build status
[03:17:11.290] Build 8892227845732701808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:17:11.290] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:4:0) after 1m0s
[03:18:11.314] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:4:0)
[03:18:11.314] Timer tick, asking Buildbucket for the build status
[03:18:11.681] Build 8892227845732701808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:18:11.681] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:5:0) after 1m0s
[03:19:11.702] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:5:0)
[03:19:11.702] Timer tick, asking Buildbucket for the build status
[03:19:12.696] Build 8892227845732701808: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:19:12.696] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:6:0) after 1m0s
[03:20:12.105] Received PubSub notification, asking Buildbucket for the build status
[03:20:12.384] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:12.745] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:6:0)
[03:20:12.745] Timer tick, asking Buildbucket for the build status
[03:20:13.032] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:13.032] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:8:0) after 1m0s
[03:21:13.059] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:8:0)
[03:21:13.059] Timer tick, asking Buildbucket for the build status
[03:21:13.313] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:13.313] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:9:0) after 1m0s
[03:22:13.332] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:9:0)
[03:22:13.332] Timer tick, asking Buildbucket for the build status
[03:22:13.490] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:13.490] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:10:0) after 1m0s
[03:23:13.507] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:10:0)
[03:23:13.507] Timer tick, asking Buildbucket for the build status
[03:23:14.679] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:14.679] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:11:0) after 1m0s
[03:24:14.698] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:11:0)
[03:24:14.698] Timer tick, asking Buildbucket for the build status
[03:24:15.636] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:15.636] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:12:0) after 1m0s
[03:25:15.685] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:12:0)
[03:25:15.685] Timer tick, asking Buildbucket for the build status
[03:25:15.827] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:15.827] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:13:0) after 1m0s
[03:26:15.847] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:13:0)
[03:26:15.847] Timer tick, asking Buildbucket for the build status
[03:26:17.195] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:17.195] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:14:0) after 1m0s
[03:27:17.227] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:14:0)
[03:27:17.227] Timer tick, asking Buildbucket for the build status
[03:27:17.484] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:17.484] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:15:0) after 1m0s
[03:28:17.482] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:15:0)
[03:28:17.482] Timer tick, asking Buildbucket for the build status
[03:28:17.844] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:17.844] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:16:0) after 1m0s
[03:29:18.004] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:16:0)
[03:29:18.004] Timer tick, asking Buildbucket for the build status
[03:29:18.241] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:18.241] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:17:0) after 1m0s
[03:30:18.295] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:17:0)
[03:30:18.295] Timer tick, asking Buildbucket for the build status
[03:30:18.662] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:18.662] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:18:0) after 1m0s
[03:31:18.840] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:18:0)
[03:31:18.840] Timer tick, asking Buildbucket for the build status
[03:31:19.387] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:19.387] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:19:0) after 1m0s
[03:32:19.404] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:19:0)
[03:32:19.404] Timer tick, asking Buildbucket for the build status
[03:32:19.901] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:19.901] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:20:0) after 1m0s
[03:33:19.958] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:20:0)
[03:33:19.958] Timer tick, asking Buildbucket for the build status
[03:33:21.023] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:21.023] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:21:0) after 1m0s
[03:34:21.044] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:21:0)
[03:34:21.044] Timer tick, asking Buildbucket for the build status
[03:34:22.344] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:22.344] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:22:0) after 1m0s
[03:35:22.377] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:22:0)
[03:35:22.377] Timer tick, asking Buildbucket for the build status
[03:35:22.601] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:22.601] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:23:0) after 1m0s
[03:36:22.625] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:23:0)
[03:36:22.625] Timer tick, asking Buildbucket for the build status
[03:36:22.874] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:22.874] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:24:0) after 1m0s
[03:37:22.902] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:24:0)
[03:37:22.902] Timer tick, asking Buildbucket for the build status
[03:37:23.641] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:23.641] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:25:0) after 1m0s
[03:38:23.630] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:25:0)
[03:38:23.630] Timer tick, asking Buildbucket for the build status
[03:38:23.904] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:23.904] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:26:0) after 1m0s
[03:39:23.937] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:26:0)
[03:39:23.937] Timer tick, asking Buildbucket for the build status
[03:39:24.454] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:24.454] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:27:0) after 1m0s
[03:40:24.520] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:27:0)
[03:40:24.520] Timer tick, asking Buildbucket for the build status
[03:40:24.738] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:24.738] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:28:0) after 1m0s
[03:41:24.793] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:28:0)
[03:41:24.793] Timer tick, asking Buildbucket for the build status
[03:41:25.061] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:25.061] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:29:0) after 1m0s
[03:42:25.093] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:29:0)
[03:42:25.093] Timer tick, asking Buildbucket for the build status
[03:42:25.292] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:25.292] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:30:0) after 1m0s
[03:43:25.356] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:30:0)
[03:43:25.356] Timer tick, asking Buildbucket for the build status
[03:43:26.583] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:26.583] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:31:0) after 1m0s
[03:44:26.614] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:31:0)
[03:44:26.614] Timer tick, asking Buildbucket for the build status
[03:44:26.832] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:26.832] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:32:0) after 1m0s
[03:45:26.915] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:32:0)
[03:45:26.915] Timer tick, asking Buildbucket for the build status
[03:45:27.178] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:27.178] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:33:0) after 1m0s
[03:46:27.245] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:33:0)
[03:46:27.245] Timer tick, asking Buildbucket for the build status
[03:46:27.417] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:27.417] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:34:0) after 1m0s
[03:47:27.472] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:34:0)
[03:47:27.472] Timer tick, asking Buildbucket for the build status
[03:47:27.683] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:27.683] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:35:0) after 1m0s
[03:48:27.753] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:35:0)
[03:48:27.753] Timer tick, asking Buildbucket for the build status
[03:48:27.960] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:27.960] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:36:0) after 1m0s
[03:49:28.084] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:36:0)
[03:49:28.084] Timer tick, asking Buildbucket for the build status
[03:49:28.251] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:28.251] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:37:0) after 1m0s
[03:50:28.291] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:37:0)
[03:50:28.291] Timer tick, asking Buildbucket for the build status
[03:50:28.819] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:28.819] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:38:0) after 1m0s
[03:51:28.855] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:38:0)
[03:51:28.855] Timer tick, asking Buildbucket for the build status
[03:51:29.108] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:29.108] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:39:0) after 1m0s
[03:52:29.093] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:39:0)
[03:52:29.093] Timer tick, asking Buildbucket for the build status
[03:52:29.711] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:29.711] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:40:0) after 1m0s
[03:53:29.766] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:40:0)
[03:53:29.766] Timer tick, asking Buildbucket for the build status
[03:53:30.126] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:30.126] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:41:0) after 1m0s
[03:54:30.117] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:41:0)
[03:54:30.117] Timer tick, asking Buildbucket for the build status
[03:54:30.271] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:30.271] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:42:0) after 1m0s
[03:55:30.300] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:42:0)
[03:55:30.300] Timer tick, asking Buildbucket for the build status
[03:55:30.462] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:30.462] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:43:0) after 1m0s
[03:56:30.494] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:43:0)
[03:56:30.494] Timer tick, asking Buildbucket for the build status
[03:56:30.734] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:30.734] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:44:0) after 1m0s
[03:57:30.709] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:44:0)
[03:57:30.709] Timer tick, asking Buildbucket for the build status
[03:57:31.003] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:31.003] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:45:0) after 1m0s
[03:58:31.226] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:45:0)
[03:58:31.226] Timer tick, asking Buildbucket for the build status
[03:58:31.703] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:31.703] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:46:0) after 1m0s
[03:59:31.891] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:46:0)
[03:59:31.891] Timer tick, asking Buildbucket for the build status
[03:59:33.272] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:33.273] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:47:0) after 1m0s
[04:00:33.419] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:47:0)
[04:00:33.419] Timer tick, asking Buildbucket for the build status
[04:00:34.057] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:34.057] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:48:0) after 1m0s
[04:01:34.181] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:48:0)
[04:01:34.181] Timer tick, asking Buildbucket for the build status
[04:01:34.802] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:34.802] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:49:0) after 1m0s
[04:02:34.903] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:49:0)
[04:02:34.903] Timer tick, asking Buildbucket for the build status
[04:02:35.063] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:35.063] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:50:0) after 1m0s
[04:03:35.123] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:50:0)
[04:03:35.123] Timer tick, asking Buildbucket for the build status
[04:03:35.304] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:35.304] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:51:0) after 1m0s
[04:04:35.341] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:51:0)
[04:04:35.341] Timer tick, asking Buildbucket for the build status
[04:04:35.501] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:35.501] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:52:0) after 1m0s
[04:05:35.962] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:52:0)
[04:05:35.962] Timer tick, asking Buildbucket for the build status
[04:05:36.171] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:36.171] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:53:0) after 1m0s
[04:06:36.242] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:53:0)
[04:06:36.242] Timer tick, asking Buildbucket for the build status
[04:06:37.163] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:37.163] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:54:0) after 1m0s
[04:07:37.235] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:54:0)
[04:07:37.235] Timer tick, asking Buildbucket for the build status
[04:07:37.475] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:37.475] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:55:0) after 1m0s
[04:08:37.508] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:55:0)
[04:08:37.508] Timer tick, asking Buildbucket for the build status
[04:08:38.595] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:38.595] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:56:0) after 1m0s
[04:09:38.669] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:56:0)
[04:09:38.669] Timer tick, asking Buildbucket for the build status
[04:09:38.845] Build 8892227845732701808: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:38.845] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9057657908828013072:57:0) after 1m0s
[04:10:30.926] Received PubSub notification, asking Buildbucket for the build status
[04:10:31.376] Build 8892227845732701808: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:10:31.376] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1578111028695742",
"created_by": "project:chromium",
"created_ts": "1578107710100460",
"id": "8892227845732701808",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8ea17a540f636103c9bba470d1a067d3e10f5777\"}}",
"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-east1-d-8-22-5e5f\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 166949, \"got_angle_revision\": \"81ee4d29192dcd0bdef05d39829d7c37e231b0e2\", \"got_dawn_revision\": \"87d31381588b26edb3da1d75dd0588b2df4dd968\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"8ea17a540f636103c9bba470d1a067d3e10f5777\", \"got_revision_cp\": \"refs/heads/master@{#728367}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"10a900e5ffaffdffe2806b1507af43a74acdfe9e\", \"got_v8_revision\": \"2de68515d603bebe3a3048fd3c34db3c48e0e958\", \"got_v8_revision_cp\": \"refs/heads/8.1.89@{#1}\", \"got_webrtc_revision\": \"f3aa6326b8e21f627b9fba72040122723251999b\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30149}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8ea17a540f636103c9bba470d1a067d3e10f5777\", \"swarm_hashes_refs/heads/master(at){#728367}_without_patch\": {\"accessibility_unittests\": \"d410fff4e201c5bd5f64fa0d84949a20b0dcf367\", \"angle_unittests\": \"d2f6221637f16394115e57d834e02c7b1b95b5d4\", \"app_shell_unittests\": \"6c909028673b791bfef524f68f6217295a1a2116\", \"aura_unittests\": \"6e569e802b0b890eeda1f23269817ca1c7208e12\", \"base_unittests\": \"8c35eeeecf5914080c23a7691723cda5f09b032c\", \"base_util_unittests\": \"972b27d73ccc127bf2fc93cca7231df124d17d65\", \"blink_common_unittests\": \"93bd218fbaac7903b0b9d053cf9aff1bd33d5b31\", \"blink_fuzzer_unittests\": \"e301d477191749515404fcbc03eca581a5580513\", \"blink_heap_unittests\": \"2d928f56f506e02d8ff4364eaba5bd17d9bd8b3b\", \"blink_platform_unittests\": \"433228d006547dcef355db0f0960daa464fa274b\", \"blink_python_tests\": \"76a73bd1e4c43805603ac0d810813957cab27dea\", \"blink_unittests\": \"a7b3dab0f853162e454544dca44c383aa95ac7af\", \"blink_web_tests\": \"c09b0d864374e4f2b9bb6c245aefd55322f4a623\", \"boringssl_crypto_tests\": \"c3488172450e852f5a79754d607a356aa57153a2\", \"boringssl_ssl_tests\": \"f4ecf3223a2fd8db83bd59d6307a7974c8050521\", \"browser_tests\": \"7b59a652195bef88fa780df5142007cb42c87865\", \"cacheinvalidation_unittests\": \"f107176ff902ff677b7b1e24e61803a33cd322b8\", \"capture_unittests\": \"d3e963759f106440017a6dd97b244883d2d82966\", \"cast_unittests\": \"849f125fdafa6cb50b55a046ac1f3f9e8f2af9e8\", \"cc_unittests\": \"08ba5fc4cf600d36eb1c39197e51b0fedfdfa7e9\", \"chrome_app_unittests\": \"268204b23b798497a9d0e590bffb16779081d418\", \"chromedriver_unittests\": \"52dc5df37e9e7b12ca7e5ddc7faf28e27338e7d7\", \"components_browsertests\": \"d2a721ffc7395156c949dd0d732cafc265415097\", \"components_unittests\": \"9bce27da7289f4de090e5728f2d499787d7fb5d1\", \"compositor_unittests\": \"22aa3b97f0c409f6b44840a037021a461fe83734\", \"content_browsertests\": \"db12be97049bdfa7180462c9ef25b46eaf6199e3\", \"content_shell_crash_test\": \"8894c04c0ba8a9cce4ce5c4659b123d1895378c9\", \"content_unittests\": \"19bd7ca5b722591c6954d75a9c17e87562f8402d\", \"crashpad_tests\": \"f848f5e2fb77248b5cee5159bfa9c685df77b810\", \"cronet_tests\": \"05da205a585dee3c1d22c62daca265b96f39ef09\", \"cronet_unittests\": \"fce809d1ae7511979bddddef7adbdd0d78b31b2f\", \"crypto_unittests\": \"202ecea992006442f6a0b5006d6acd69b3d0228c\", \"dbus_unittests\": \"77d972ce86ab934366fe47af17ef254d185bd384\", \"device_unittests\": \"da95a8aaecc5cc75c3b18ea95a9ef58e435c6320\", \"devtools_lint_check\": \"7ca0f2393fea57fc4accb1061cde6783746e3575\", \"devtools_type_check\": \"6584cc3700a8ce5f2087dadefd2a8daba481142d\", \"display_unittests\": \"3bc55022c5c81e31efdfab1b4e4422d4320e8dea\", \"events_unittests\": \"83023dbae003559150857da48f239b09b4528468\", \"extensions_browsertests\": \"79cef79311607b1468981307b410ede8033838d6\", \"extensions_unittests\": \"d9f813af97945cd2895d228c9a54297d7b0483ba\", \"filesystem_service_unittests\": \"b5835790fffbe900acccddf391590b3fbab438f9\", \"flatbuffers_unittests\": \"034d1c63a8199a680c9bbf29b5866b1c851e28ee\", \"gcm_unit_tests\": \"6d65ed732542eec9ea938d965cee296d199129a1\", \"gfx_unittests\": \"cdf5fbf885e0f26bb9a69182ac904b1794fb2477\", \"gin_unittests\": \"2c463169db22a96b008d55595d83727ff9a6b359\", \"google_apis_unittests\": \"ce7819a534ecc226d054aea4a1825a7b81e517d9\", \"gpu_unittests\": \"af2040dc442c920c6027edb5542834091cb98b62\", \"grit_python_unittests\": \"5d2561226d0db6aa6e2a7322c9c06178bd9c6346\", \"gwp_asan_unittests\": \"00d2d2b5a60b349a787219b1d56795fd6ed3e102\", \"headless_browsertests\": \"7930232da7540d17546d6f73564e7e72452e09fe\", \"headless_unittests\": \"ebc3e350268e8d5a35d6eb4c7703b5fc4bed8609\", \"interactive_ui_tests\": \"d74eec7711c59d7045153b280683aa4856c92b0c\", \"ipc_tests\": \"e4a37ffe7a8989146f928fd04e397fd1a35e3c8d\", \"jingle_unittests\": \"8b14739cf86b1753ede34dd96724ee81b103d014\", \"latency_unittests\": \"08e8244f2054cc5c566ed488644cd57800f873a8\", \"libjingle_xmpp_unittests\": \"dedc9ee71607d54a4b78b513661a70af000e692e\", \"media_blink_unittests\": \"fc6058a670e6a32ef4ceca81d715827609bf66ca\", \"media_service_unittests\": \"1d552f7fedb784ac2914bd25a980c337429f2258\", \"media_unittests\": \"396fcfb3a246d4ed10fce9afadd5220dd22f6de8\", \"message_center_unittests\": \"93b64d4fa08f36066fc95772f2b588f86df19dd5\", \"metrics_python_tests\": \"41386627d77414bd63865c3ff5b6242a43a9b8ae\", \"midi_unittests\": \"130e3a9605050ea41572eb7615de49c695f47631\", \"mojo_core_unittests\": \"88142d958bc715fceb5ea3215a612d3580239cc8\", \"mojo_unittests\": \"f3f75d1a8d0730a247f1360e7ebbb4dc2d55b0b6\", \"nacl_helper_nonsfi_unittests\": \"0b857dcb70c58be886f0ce34596911acceacf3d4\", \"nacl_loader_unittests\": \"7e025a0ca134512df84814f592c03ade69d302e0\", \"native_theme_unittests\": \"8b7370f958885128bddb9b4d49b213890cb30bb8\", \"net_unittests\": \"aa57451cc41032d9f57e80a255e971b1b9136dd0\", \"pdf_unittests\": \"a10f31d435580685de96d1517a4a4cc574eb356e\", \"perfetto_unittests\": \"0060bf79750420020bc8b374356b63590c5fe0ba\", \"ppapi_unittests\": \"49f56c1e390a3f5535611ae1692e689ea0887c22\", \"printing_unittests\": \"ab984c47337bc3e5366c9c3d545529e6097b7b65\", \"remoting_unittests\": \"41cd22ca0f1237ed371d430f7f1c3208118e26f6\", \"sandbox_linux_unittests\": \"7a30962cc3849420a2aa05b5299fc2b94d226a00\", \"service_manager_unittests\": \"acffbb0c907fcfc789e529837ff379e970c8bd81\", \"services_unittests\": \"d13cd53ea1cb2e403fa4c651ff921b2025749958\", \"shell_dialogs_unittests\": \"1c732951e3401dc0137e97883b926c997a726754\", \"skia_unittests\": \"9b786ef7fed02e6da95d602ad869d36a0c325421\", \"snapshot_unittests\": \"209e25a320907896aeb74b6da1c89ca1553ed788\", \"sql_unittests\": \"dc36d5760a3ebb8f109935dc07d991d838d1b786\", \"storage_unittests\": \"9fb379c89363990764b84b200e1cc2812020201f\", \"sync_integration_tests\": \"f7dfcc40e1d064e28212c9670f72a7c8925ce998\", \"telemetry_gpu_unittests\": \"fb3da4617c83c4aad06b39ff1135826d4603a542\", \"telemetry_perf_unittests\": \"dd81a6c80dd5f3969c023d7e3660d1766c9e6a9b\", \"telemetry_unittests\": \"556ab1e8186e4318f56e075fd9871ae2b2ef9aeb\", \"traffic_annotation_auditor_unittests\": \"5faab5c857cb48ffb77b898fee684400f087263d\", \"ui_base_unittests\": \"989768da5fa1cb793f7e04d8a0159bb4522e4205\", \"ui_touch_selection_unittests\": \"e426d139cdd8588d7a0bb0f698f06f5ecc5c87db\", \"unit_tests\": \"f66d43a0c35c9e49fed208d579145bd784d142b0\", \"url_unittests\": \"78e7fcaceb0b22c1a46583a7eac8116cd177bf04\", \"views_perftests\": \"36a801ce1b2812be1888f743b16ab9f8ac3702c6\", \"views_unittests\": \"e8a6deb30533870bc0af6dac2317f043e49e7d6d\", \"viz_unittests\": \"2a219eff282846e09897ef565969a749cf3102a5\", \"vr_common_unittests\": \"63d9159f2cffe066de231962c62d2319aa3a85f7\", \"vr_pixeltests\": \"f7fe4cfad185cc7e508245b656982a27d0eb6069\", \"webdriver_wpt_tests\": \"3fc34799a62e9563c74e961ff32f1251c49fbf14\", \"weblayer_browsertests\": \"19ad20729043a77eb3c6e957e7a7d122c324a923\", \"wm_unittests\": \"449d97a525c2515cc78304b7e8c1ee368b5d4688\", \"wtf_unittests\": \"f4213b26c06ce4b1e06d82d10468921cc29f8c03\", \"xr_browser_tests\": \"65312100630d520ae47c41d03c90bb3eeb9e9243\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_65fafd0c860c8cf0043a2ec0f6482d6e089dd429d22310040aa34c8b96106827_v2\", \"builder_93045653dd8238f9b5a16956c3abf0c7402036195ded7f89ac6ed546092c13e3_v2\", \"builder_d0ba3cd99c36b697d8651140d51bf4b4f4b9cd9aa25af11363af5ede5f6e35b1_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-east1-d-8-22-5e5f\"], \"image\": [\"chrome-xenial-19121500-b03549ec744\"], \"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\": [\"4733-6bd8c41\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1578108011135017",
"status": "COMPLETED",
"status_changed_ts": "1578111029661489",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/166949",
"builder:Linux Builder (dbg)",
"buildset:commit/git/8ea17a540f636103c9bba470d1a067d3e10f5777",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8ea17a540f636103c9bba470d1a067d3e10f5777",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9057657908828013072",
"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/8892227845732701808/+/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:49875e355fec7710",
"user_agent:luci-scheduler"
],
"updated_ts": "1578111029825555",
"url": "https://ci.chromium.org/b/8892227845732701808",
"utcnow_ts": "1578111031342646"
}
}
[04:10:31.376] Invocation finished in 55m23.612391575s with status SUCCEEDED