[22:35:22.378] New invocation is queued and will start shortly
[22:35:23.466] Starting the invocation (attempt 1)
[22:35:23.500] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:35:23.500] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:35:23.501] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027416122829696432",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"109bc30b57aac33be2fa1f5ff9be8c1a05534198\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9027416122829696432",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/109bc30b57aac33be2fa1f5ff9be8c1a05534198",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/109bc30b57aac33be2fa1f5ff9be8c1a05534198",
"gitiles_ref:refs/heads/master"
]
}
[22:35:24.306] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606948523584495",
"id": "8861986060892212928",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"109bc30b57aac33be2fa1f5ff9be8c1a05534198\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606948524061885",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/178279",
"builder:Linux Builder (dbg)",
"buildset:commit/git/109bc30b57aac33be2fa1f5ff9be8c1a05534198",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/109bc30b57aac33be2fa1f5ff9be8c1a05534198",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027416122829696432",
"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/8861986060892212928/+/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": "1606948524062032",
"url": "https://ci.chromium.org/b/8861986060892212928",
"utcnow_ts": "1606948524287524"
}
}
[22:35:24.306] Task URL: https://ci.chromium.org/b/8861986060892212928
[22:35:24.306] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:2:0) after 1m0s
[22:35:49.861] Received PubSub notification, asking Buildbucket for the build status
[22:35:49.956] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:24.437] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:2:0)
[22:36:24.437] Timer tick, asking Buildbucket for the build status
[22:36:24.820] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:24.820] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:4:0) after 1m0s
[22:37:24.927] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:4:0)
[22:37:24.927] Timer tick, asking Buildbucket for the build status
[22:37:25.059] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:25.059] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:5:0) after 1m0s
[22:38:25.082] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:5:0)
[22:38:25.082] Timer tick, asking Buildbucket for the build status
[22:38:25.151] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:25.151] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:6:0) after 1m0s
[22:39:25.205] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:6:0)
[22:39:25.206] Timer tick, asking Buildbucket for the build status
[22:39:32.121] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:32.121] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:7:0) after 1m0s
[22:40:32.500] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:7:0)
[22:40:32.500] Timer tick, asking Buildbucket for the build status
[22:40:32.612] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:32.612] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:8:0) after 1m0s
[22:41:32.652] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:8:0)
[22:41:32.652] Timer tick, asking Buildbucket for the build status
[22:41:33.129] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:33.129] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:9:0) after 1m0s
[22:42:33.137] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:9:0)
[22:42:33.137] Timer tick, asking Buildbucket for the build status
[22:42:33.438] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:33.438] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:10:0) after 1m0s
[22:43:33.569] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:10:0)
[22:43:33.569] Timer tick, asking Buildbucket for the build status
[22:43:33.639] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:33.639] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:11:0) after 1m0s
[22:44:33.682] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:11:0)
[22:44:33.682] Timer tick, asking Buildbucket for the build status
[22:44:33.744] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:33.744] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:12:0) after 1m0s
[22:45:33.872] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:12:0)
[22:45:33.872] Timer tick, asking Buildbucket for the build status
[22:45:34.169] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:34.169] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:13:0) after 1m0s
[22:46:34.198] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:13:0)
[22:46:34.198] Timer tick, asking Buildbucket for the build status
[22:46:34.304] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:34.304] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:14:0) after 1m0s
[22:47:34.348] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:14:0)
[22:47:34.348] Timer tick, asking Buildbucket for the build status
[22:47:34.417] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:34.417] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:15:0) after 1m0s
[22:48:34.444] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:15:0)
[22:48:34.444] Timer tick, asking Buildbucket for the build status
[22:48:34.512] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:34.512] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:16:0) after 1m0s
[22:49:34.556] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:16:0)
[22:49:34.556] Timer tick, asking Buildbucket for the build status
[22:49:34.631] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:34.631] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:17:0) after 1m0s
[22:50:34.731] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:17:0)
[22:50:34.731] Timer tick, asking Buildbucket for the build status
[22:50:34.890] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:34.890] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:18:0) after 1m0s
[22:51:34.926] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:18:0)
[22:51:34.926] Timer tick, asking Buildbucket for the build status
[22:51:35.482] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:35.482] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:19:0) after 1m0s
[22:52:36.057] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:19:0)
[22:52:36.057] Timer tick, asking Buildbucket for the build status
[22:52:36.129] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:36.129] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:20:0) after 1m0s
[22:53:36.259] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:20:0)
[22:53:36.259] Timer tick, asking Buildbucket for the build status
[22:53:36.366] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:36.366] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:21:0) after 1m0s
[22:54:36.410] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:21:0)
[22:54:36.410] Timer tick, asking Buildbucket for the build status
[22:54:36.497] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:36.497] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:22:0) after 1m0s
[22:55:36.603] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:22:0)
[22:55:36.603] Timer tick, asking Buildbucket for the build status
[22:55:36.707] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:36.707] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:23:0) after 1m0s
[22:56:36.731] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:23:0)
[22:56:36.731] Timer tick, asking Buildbucket for the build status
[22:56:36.783] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:36.783] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:24:0) after 1m0s
[22:57:36.836] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:24:0)
[22:57:36.836] Timer tick, asking Buildbucket for the build status
[22:57:36.949] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:36.949] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:25:0) after 1m0s
[22:58:36.993] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:25:0)
[22:58:36.993] Timer tick, asking Buildbucket for the build status
[22:58:37.059] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:37.059] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:26:0) after 1m0s
[22:59:37.157] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:26:0)
[22:59:37.157] Timer tick, asking Buildbucket for the build status
[22:59:37.228] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:37.228] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:27:0) after 1m0s
[23:00:37.320] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:27:0)
[23:00:37.320] Timer tick, asking Buildbucket for the build status
[23:00:37.442] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:37.442] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:28:0) after 1m0s
[23:01:37.494] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:28:0)
[23:01:37.494] Timer tick, asking Buildbucket for the build status
[23:01:37.699] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:37.699] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:29:0) after 1m0s
[23:02:37.757] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:29:0)
[23:02:37.757] Timer tick, asking Buildbucket for the build status
[23:02:37.822] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:37.822] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:30:0) after 1m0s
[23:03:37.857] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:30:0)
[23:03:37.857] Timer tick, asking Buildbucket for the build status
[23:03:37.924] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:37.924] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:31:0) after 1m0s
[23:04:37.974] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:31:0)
[23:04:37.974] Timer tick, asking Buildbucket for the build status
[23:04:38.046] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:38.046] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:32:0) after 1m0s
[23:05:38.084] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:32:0)
[23:05:38.084] Timer tick, asking Buildbucket for the build status
[23:05:38.180] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:38.180] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:33:0) after 1m0s
[23:06:38.242] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:33:0)
[23:06:38.242] Timer tick, asking Buildbucket for the build status
[23:06:38.311] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:38.311] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:34:0) after 1m0s
[23:07:38.343] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:34:0)
[23:07:38.343] Timer tick, asking Buildbucket for the build status
[23:07:38.411] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:38.411] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:35:0) after 1m0s
[23:08:38.534] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:35:0)
[23:08:38.534] Timer tick, asking Buildbucket for the build status
[23:08:38.586] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:38.586] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:36:0) after 1m0s
[23:09:38.691] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:36:0)
[23:09:38.691] Timer tick, asking Buildbucket for the build status
[23:09:38.842] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:38.842] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:37:0) after 1m0s
[23:10:39.034] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:37:0)
[23:10:39.034] Timer tick, asking Buildbucket for the build status
[23:10:39.407] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:39.407] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:38:0) after 1m0s
[23:11:39.500] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:38:0)
[23:11:39.500] Timer tick, asking Buildbucket for the build status
[23:11:39.579] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:39.579] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:39:0) after 1m0s
[23:12:39.680] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:39:0)
[23:12:39.680] Timer tick, asking Buildbucket for the build status
[23:12:39.745] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:39.745] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:40:0) after 1m0s
[23:13:39.899] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:40:0)
[23:13:39.899] Timer tick, asking Buildbucket for the build status
[23:13:39.958] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:39.958] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:41:0) after 1m0s
[23:14:40.254] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:41:0)
[23:14:40.254] Timer tick, asking Buildbucket for the build status
[23:14:40.387] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:40.387] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:42:0) after 1m0s
[23:15:40.390] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:42:0)
[23:15:40.390] Timer tick, asking Buildbucket for the build status
[23:15:40.848] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:40.848] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:43:0) after 1m0s
[23:16:40.840] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:43:0)
[23:16:40.840] Timer tick, asking Buildbucket for the build status
[23:16:40.924] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:40.924] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:44:0) after 1m0s
[23:17:40.960] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:44:0)
[23:17:40.960] Timer tick, asking Buildbucket for the build status
[23:17:41.401] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:41.401] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:45:0) after 1m0s
[23:18:41.548] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:45:0)
[23:18:41.548] Timer tick, asking Buildbucket for the build status
[23:18:41.629] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:41.629] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:46:0) after 1m0s
[23:19:41.646] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:46:0)
[23:19:41.646] Timer tick, asking Buildbucket for the build status
[23:19:41.947] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:41.947] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:47:0) after 1m0s
[23:20:42.031] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:47:0)
[23:20:42.031] Timer tick, asking Buildbucket for the build status
[23:20:42.250] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:42.250] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:48:0) after 1m0s
[23:21:42.327] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:48:0)
[23:21:42.327] Timer tick, asking Buildbucket for the build status
[23:21:42.393] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:42.393] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:49:0) after 1m0s
[23:22:42.436] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:49:0)
[23:22:42.436] Timer tick, asking Buildbucket for the build status
[23:22:42.605] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:42.605] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:50:0) after 1m0s
[23:23:42.631] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:50:0)
[23:23:42.631] Timer tick, asking Buildbucket for the build status
[23:23:42.791] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:42.791] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:51:0) after 1m0s
[23:24:42.780] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:51:0)
[23:24:42.780] Timer tick, asking Buildbucket for the build status
[23:24:42.943] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:42.943] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:52:0) after 1m0s
[23:25:42.957] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:52:0)
[23:25:42.957] Timer tick, asking Buildbucket for the build status
[23:25:43.090] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:43.090] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:53:0) after 1m0s
[23:26:43.157] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:53:0)
[23:26:43.157] Timer tick, asking Buildbucket for the build status
[23:26:44.740] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:44.740] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:54:0) after 1m0s
[23:27:44.827] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:54:0)
[23:27:44.827] Timer tick, asking Buildbucket for the build status
[23:27:44.902] Build 8861986060892212928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:44.902] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9027416122829696432:55:0) after 1m0s
[23:27:47.625] Received PubSub notification, asking Buildbucket for the build status
[23:27:47.918] Build 8861986060892212928: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:27:47.922] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606951666230771",
"created_by": "project:chromium",
"created_ts": "1606948523584495",
"id": "8861986060892212928",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"109bc30b57aac33be2fa1f5ff9be8c1a05534198\"}}",
"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\": \"109bc30b57aac33be2fa1f5ff9be8c1a05534198\", \"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-east1-d-8-109-l3pz\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.linux\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 178279, \"got_angle_revision\": \"0c47015b57a03230e0cab10532c3deed80604512\", \"got_dawn_revision\": \"429a12542f160a36d87c1daa6c3102bce072207e\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"109bc30b57aac33be2fa1f5ff9be8c1a05534198\", \"got_revision_cp\": \"refs/heads/master@{#833007}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"bfb211914759cc5deffbe9419cd1c2cae0573488\", \"got_v8_revision\": \"b3456dd37acf448bb7e41b1dfc6eee5d28f38081\", \"got_v8_revision_cp\": \"refs/heads/8.9.75@{#1}\", \"got_webrtc_revision\": \"01b3e24a83564584ab0736074776e634c5ca6bb0\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32741}\", \"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\": \"109bc30b57aac33be2fa1f5ff9be8c1a05534198\", \"swarm_hashes_refs/heads/master(at){#833007}_without_patch\": {\"absl_hardening_tests\": \"4ed175dfc7309f24dba5e47429eefa1b4db0a538\", \"accessibility_unittests\": \"5c7a23ece6600bca4cf83efba35c04d8e5716aeb\", \"angle_unittests\": \"54a2c6be5942d6b8df50f26532105baadc711365\", \"app_shell_unittests\": \"3a3aab9aba70ba3f175ce169313fed3d8704312c\", \"aura_unittests\": \"624912ffb03b11dd49df358c80ef8ffdb3ea4c71\", \"base_unittests\": \"120a575546b257dea8102b8f55b478e57ab283e9\", \"base_util_unittests\": \"000def0150f56df577bb1e5a3b1a8f101bd49422\", \"blink_common_unittests\": \"a75abe3baa4c768dfe8803a2c36c5c51d314309a\", \"blink_fuzzer_unittests\": \"f63bc80e0bce7cb4365962104a61b7ec7e7a59fd\", \"blink_heap_unittests\": \"56099dcc3acef5219b1c349532c24305029a68e5\", \"blink_platform_unittests\": \"effe92962d40a5a2d872a1a2002d7bdda2823443\", \"blink_python_tests\": \"47f136dec7c11e1493a36329c292a525d40b75ac\", \"blink_unittests\": \"385ab00244e06267a712e021b71f24db614867cd\", \"blink_web_tests\": \"2ea6b7b0b9a3e4436ca35da5ffa59a2542f3ca2b\", \"boringssl_crypto_tests\": \"1b759ef2efc1261b22b856892921e73319f65401\", \"boringssl_ssl_tests\": \"7c44a409fd8abf9a0b13aa699c20685088d97aa0\", \"browser_tests\": \"efc81015c695001cd4f8c9c1f4e9445062390dd4\", \"capture_unittests\": \"66df470da2e350b7fe54a161997aafbb9f6813c5\", \"cast_unittests\": \"1469c875ef2ab342f4eabbae4eac600bbde20eff\", \"cc_unittests\": \"ce4c8d8b75d519a485b229acd8007469540f2f7d\", \"chrome_app_unittests\": \"decfcc55de891d57d8ce45416bde84f6b5ff4117\", \"chromedriver_unittests\": \"ca5f29fd5001ccbcc9618a3a7fbd4c7b28cb5e18\", \"components_browsertests\": \"b066634f2aa80b778abf640aafc300f939df76ed\", \"components_unittests\": \"fbc88923cf6002dcba72da04dbf86e9c47efa3fc\", \"compositor_unittests\": \"fc0bc15ec85036a003928e85de269231d3dd28ce\", \"content_browsertests\": \"8f6e92ee528a66fb45307ee8b40a18dbac0731c2\", \"content_shell_crash_test\": \"be20414d9ec3fb67dc6bec6b57d6c74aff62c972\", \"content_unittests\": \"86ca477acd05f6bc7f26eff03af3a99623a33d93\", \"crashpad_tests\": \"b7ed7cfadf1032eb03544c8ea89b63c04f9ab96f\", \"cronet_tests\": \"66def3f712619f93900a9f2e1c8e54f70aa2d79a\", \"cronet_unittests\": \"83984cb9a8eaec338e0defcee840a0233fb3e369\", \"crypto_unittests\": \"7085ba69f135dc475d018e0cf67a1fa4a539f80d\", \"dbus_unittests\": \"577c3782e38945d755e2371511e4a663c64b08af\", \"device_unittests\": \"4521368b875aff54589b7dc80ebfd05764a45381\", \"display_unittests\": \"98cf7cbc70150b4213e686e524a72eda2fbaf0e5\", \"events_unittests\": \"5c12a28ce81b7ec86f33863815ae0c7bf1f9a876\", \"extension_docserver_python_unittests\": \"a4dee5052e5099612db3fc6255da6770a217af23\", \"extensions_browsertests\": \"76b7f5d73551b840e6ebd97f7056e104c2d2af40\", \"extensions_unittests\": \"086336a45807503ceeefdd19471e032e8f7c1a2b\", \"filesystem_service_unittests\": \"ee2b94b9e6d623e2eeaf0391d611b6682cd20b0c\", \"flatbuffers_unittests\": \"4d7f6411689e0ca026ebaa10a0eb906a6c506cdd\", \"gcm_unit_tests\": \"4c2b6518ffabe22c4e65f451681606bf6d2f6fa3\", \"gfx_unittests\": \"8a0f9df4ed946228bf1678c8fae645f1fc88f1d5\", \"gin_unittests\": \"691d0975832b5ddab9d088eb8d6f90a1cf256bcb\", \"google_apis_unittests\": \"be281b16a4793f73e74948186ef52635e7df7a6e\", \"gpu_unittests\": \"18fdf73bb8fcf4829a12159f6d7772c5ddf9487f\", \"grit_python_unittests\": \"0afe932bb293928849622f4855bf5364fc3d1290\", \"gwp_asan_unittests\": \"1c8d2efce0c0398785de2dbf68ec387ad1d3128e\", \"headless_browsertests\": \"474bb6f043642167ba8a9d64cb876431739cfc79\", \"headless_unittests\": \"55d41b1ba40bd7e1c794c6909b0d0e440d4ec0a7\", \"interactive_ui_tests\": \"74336b64ecb086d62203e614c7dc203cbd67cddc\", \"ipc_tests\": \"9001a43923c087b513be8caf164a99718c9db3b2\", \"jingle_unittests\": \"309d6a51c1deba4d9d228be63dea4981d3a46cae\", \"latency_unittests\": \"c0add4d75ae69ef230b539fd2a8c3dc2535e839d\", \"libjingle_xmpp_unittests\": \"acdddd7a6356e2c3a89acd1810617031e6c63b58\", \"liburlpattern_unittests\": \"7bd89b2cc70e29fe3309d924acaed51ff079bb0a\", \"media_blink_unittests\": \"c084c4a0eeb004a3a53f6bab22a1d3b3580a168e\", \"media_unittests\": \"b206f6bb43eebf85f58a2c0b8606bdae499abb22\", \"message_center_unittests\": \"d20a03d397e0ac8c5cc33e48d0dab4ca5ab09eb5\", \"metrics_python_tests\": \"743b126b60202a51912a84aac193e030c7029206\", \"midi_unittests\": \"86424ab345b04cb7f4f09717b7ae375e09e28787\", \"mojo_core_unittests\": \"c2f27b7c8daa83d2e46f9b6bb313438a111d6c78\", \"mojo_unittests\": \"4f425d602913812903b47f6ff3af3666a813ae29\", \"nacl_helper_nonsfi_unittests\": \"6bd4879b8ea9cba6a77e5b32934c10c837e9d8ea\", \"nacl_loader_unittests\": \"afe9f49036dbeca9b1d5f268999dc0ea4167cfdc\", \"native_theme_unittests\": \"d98fc1e0dc9b3e3a3b731fbf9eeec8c98377f392\", \"net_unittests\": \"0986e484af712ea9f1f0299dfcd1ce0cb56781bf\", \"openscreen_unittests\": \"47bc308b4c516f2b67f24f91aa185a9480afa9ca\", \"pdf_unittests\": \"df96b1b0bfef73020eb746a854730648e4f9d441\", \"perfetto_unittests\": \"44e15f17e5a491f161c8631655c3754aba8fab9e\", \"ppapi_unittests\": \"b6f5a152c2874730011c6232b098dc75042e657e\", \"printing_unittests\": \"fe3e80866914e15164a52f77aedb3488254df49b\", \"remoting_unittests\": \"c8b9cd57d518f6ac70dd0342dc4fa5306853708e\", \"sandbox_linux_unittests\": \"f66bfda2900e2bc143972183152587fc8c560f9a\", \"service_manager_unittests\": \"d147e65f24056f41ef8c673826eb2fcbe00028e7\", \"services_unittests\": \"3cd327d4e0b56f7febc6cd65cfc612793fe55ee6\", \"shell_dialogs_unittests\": \"8151364d0e26f06edeac3252d5d18bd8d99e96e2\", \"skia_unittests\": \"234df62ff773b57d3bb022208228fb7baadfbac4\", \"snapshot_unittests\": \"5a2281f6caf567d9032a2b24219c03ba1d976551\", \"sql_unittests\": \"17c987823580b5580fdfc48eef9e30847a1952d0\", \"storage_unittests\": \"a4847c75cf5f9b5e12f105e468a2cea99c2bf7cd\", \"sync_integration_tests\": \"20a071bcfa2656595ab3d764a21a3ca506cf2afc\", \"telemetry_gpu_unittests\": \"b7f7a060ecc15af17f752c17bb69841e13fbcb68\", \"telemetry_perf_unittests\": \"661d110f1dbbd165fa7552423aac3171c31c00df\", \"telemetry_unittests\": \"c87ca81d148a106f25f112e7b55623c6fe7c8915\", \"traffic_annotation_auditor_unittests\": \"3b26ae6465bef99acac855c3c053970be9cb459f\", \"ui_base_unittests\": \"e71748e3fa36b4107d3d8c1a97394cc1a15f5b2c\", \"ui_touch_selection_unittests\": \"747234c8bfdeb105e74f5d6547cec8689f710dc1\", \"unit_tests\": \"588eac4b43f6a6efce388d05f92a7f7ccb625b09\", \"url_unittests\": \"20156446db5f0afaa85e72d0ff257ca3ba66689b\", \"views_perftests\": \"658b3e488f8e42a74e180e4e1eed6cf4a4e6ec53\", \"views_unittests\": \"8efd30fc1f7ab9ab91bbdf9f24dbbed99711dcb7\", \"viz_unittests\": \"e2b12f5abc1c11b20bc230d19a22e3aab34e8faf\", \"vr_common_unittests\": \"3204b6b61912eb9057b3c198438801b39854aa5d\", \"vr_pixeltests\": \"4c281166080b00f23b010c47aec33b5fe6f534aa\", \"webdriver_wpt_tests\": \"4cef0dccd4416f4e624d577564d5acc4ccba7f89\", \"weblayer_browsertests\": \"9f2bffef938d0ba28846c953c37e238c22058f2a\", \"weblayer_unittests\": \"6c16d4a37ed4da86e5b59756230bcfa5040b3f7a\", \"wm_unittests\": \"c807c2a0c5bb82694cd9e9cf5124488d955175d3\", \"wtf_unittests\": \"8651a61220f4182c24ae02d8fcd0747a484143df\", \"x11_unittests\": \"fae5b6c9a92e7b259c0c603f35d68b4fc606e203\", \"xr_browser_tests\": \"225b09bf053d8b9998b62c7426eea8746d266101\", \"zlib_unittests\": \"ebdc6d997b790008eae7d3cb7776df1ef14f28eb\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1cac3a12be5110575bfda3df59662d0c101b80b5d04053a56e3716a56e114d29_v2\", \"builder_7701d7ffc8ab5fe38a709c77d4f18478e80f1a1d01dd8053f239f119334d81d1_v2\", \"builder_859368e171259d521efea13da6023dc28f5117d74d565299fe323cf1e9b3ffc9_v2\", \"builder_8e35dc9d0a811a7a5937b2845f7d6ff611dfc73c6ea10d73e1df62dc95746f2a_v2\", \"builder_a10052c67097968898fa29710cdaa0ada58a120001760a536a006ca29eefe964_v2\", \"builder_bee64b857be6df8b7c1ab34ae7b2c6eab296c35495667b8b8424b2083d103b1e_v2\", \"builder_dbe717e93dfefb94e765b2da7ffc34b16e5134b01772a8d37ee52c1fe1505654_v2\", \"builder_e21c17032b725bcf1c922976a36352546baf3f39ec148d1e6730a93c0400d8cc_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-east1-d-8-109-l3pz\"], \"image\": [\"chrome-xenial-20112200-f7cdea8df03\"], \"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\": [\"5640-d0eb544\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1606948549197110",
"status": "COMPLETED",
"status_changed_ts": "1606951666703696",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/178279",
"builder:Linux Builder (dbg)",
"buildset:commit/git/109bc30b57aac33be2fa1f5ff9be8c1a05534198",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/109bc30b57aac33be2fa1f5ff9be8c1a05534198",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027416122829696432",
"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/8861986060892212928/+/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:503e6a1949247610",
"user_agent:luci-scheduler"
],
"updated_ts": "1606951666710489",
"url": "https://ci.chromium.org/b/8861986060892212928",
"utcnow_ts": "1606951667877860"
}
}
[23:27:47.924] Invocation finished in 52m25.57048752s with status SUCCEEDED