[09:44:33.545] New invocation is queued and will start shortly
[09:44:35.697] Starting the invocation (attempt 1)
[09:44:35.734] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:44:35.734] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:44:35.734] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9053103559728206176",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d4e866b3c27f7332f73f212f0a8927b56f9b2e90\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9053103559728206176",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/d4e866b3c27f7332f73f212f0a8927b56f9b2e90",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d4e866b3c27f7332f73f212f0a8927b56f9b2e90",
"gitiles_ref:refs/heads/master"
]
}
[09:44:36.962] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1582451075881182",
"id": "8887673496615250016",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d4e866b3c27f7332f73f212f0a8927b56f9b2e90\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582451076725766",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168921",
"builder:Linux Builder (dbg)",
"buildset:commit/git/d4e866b3c27f7332f73f212f0a8927b56f9b2e90",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d4e866b3c27f7332f73f212f0a8927b56f9b2e90",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053103559728206176",
"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/8887673496615250016/+/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": "1582451076725871",
"url": "https://ci.chromium.org/b/8887673496615250016",
"utcnow_ts": "1582451076938127"
}
}
[09:44:36.962] Task URL: https://ci.chromium.org/b/8887673496615250016
[09:44:36.962] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:2:0) after 1m0s
[09:45:40.589] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:2:0)
[09:45:40.589] Timer tick, asking Buildbucket for the build status
[09:45:40.778] Build 8887673496615250016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:45:40.778] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:3:0) after 1m0s
[09:46:40.853] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:3:0)
[09:46:40.853] Timer tick, asking Buildbucket for the build status
[09:46:41.018] Build 8887673496615250016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:46:41.018] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:4:0) after 1m0s
[09:47:41.298] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:4:0)
[09:47:41.298] Timer tick, asking Buildbucket for the build status
[09:47:41.499] Build 8887673496615250016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:47:41.499] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:5:0) after 1m0s
[09:48:41.732] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:5:0)
[09:48:41.732] Timer tick, asking Buildbucket for the build status
[09:48:41.882] Build 8887673496615250016: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:48:41.882] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:6:0) after 1m0s
[09:49:04.061] Received PubSub notification, asking Buildbucket for the build status
[09:49:04.126] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:42.048] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:6:0)
[09:49:42.048] Timer tick, asking Buildbucket for the build status
[09:49:42.291] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:42.291] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:8:0) after 1m0s
[09:50:42.312] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:8:0)
[09:50:42.312] Timer tick, asking Buildbucket for the build status
[09:50:42.419] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:42.419] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:9:0) after 1m0s
[09:51:42.600] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:9:0)
[09:51:42.600] Timer tick, asking Buildbucket for the build status
[09:51:43.051] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:43.051] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:10:0) after 1m0s
[09:52:43.025] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:10:0)
[09:52:43.025] Timer tick, asking Buildbucket for the build status
[09:52:43.295] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:43.295] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:11:0) after 1m0s
[09:53:43.335] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:11:0)
[09:53:43.335] Timer tick, asking Buildbucket for the build status
[09:53:43.444] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:43.444] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:12:0) after 1m0s
[09:54:43.515] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:12:0)
[09:54:43.515] Timer tick, asking Buildbucket for the build status
[09:54:43.655] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:43.655] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:13:0) after 1m0s
[09:55:43.678] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:13:0)
[09:55:43.678] Timer tick, asking Buildbucket for the build status
[09:55:43.795] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:43.795] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:14:0) after 1m0s
[09:56:43.863] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:14:0)
[09:56:43.863] Timer tick, asking Buildbucket for the build status
[09:56:44.045] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:44.045] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:15:0) after 1m0s
[09:57:44.094] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:15:0)
[09:57:44.094] Timer tick, asking Buildbucket for the build status
[09:57:44.279] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:44.279] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:16:0) after 1m0s
[09:58:44.304] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:16:0)
[09:58:44.304] Timer tick, asking Buildbucket for the build status
[09:58:44.423] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:44.423] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:17:0) after 1m0s
[09:59:44.450] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:17:0)
[09:59:44.450] Timer tick, asking Buildbucket for the build status
[09:59:45.161] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:45.161] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:18:0) after 1m0s
[10:00:45.285] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:18:0)
[10:00:45.285] Timer tick, asking Buildbucket for the build status
[10:00:45.432] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:45.432] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:19:0) after 1m0s
[10:01:45.527] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:19:0)
[10:01:45.527] Timer tick, asking Buildbucket for the build status
[10:01:45.631] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:45.631] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:20:0) after 1m0s
[10:02:45.750] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:20:0)
[10:02:45.750] Timer tick, asking Buildbucket for the build status
[10:02:46.211] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:46.211] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:21:0) after 1m0s
[10:03:46.212] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:21:0)
[10:03:46.212] Timer tick, asking Buildbucket for the build status
[10:03:46.547] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:46.547] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:22:0) after 1m0s
[10:04:46.798] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:22:0)
[10:04:46.798] Timer tick, asking Buildbucket for the build status
[10:04:46.922] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:46.922] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:23:0) after 1m0s
[10:05:46.960] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:23:0)
[10:05:46.960] Timer tick, asking Buildbucket for the build status
[10:05:47.637] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:47.637] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:24:0) after 1m0s
[10:06:47.761] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:24:0)
[10:06:47.761] Timer tick, asking Buildbucket for the build status
[10:06:47.878] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:47.878] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:25:0) after 1m0s
[10:07:47.913] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:25:0)
[10:07:47.913] Timer tick, asking Buildbucket for the build status
[10:07:48.075] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:48.075] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:26:0) after 1m0s
[10:08:48.164] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:26:0)
[10:08:48.164] Timer tick, asking Buildbucket for the build status
[10:08:48.494] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:48.494] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:27:0) after 1m0s
[10:09:48.551] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:27:0)
[10:09:48.551] Timer tick, asking Buildbucket for the build status
[10:09:48.966] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:48.966] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:28:0) after 1m0s
[10:10:49.068] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:28:0)
[10:10:49.068] Timer tick, asking Buildbucket for the build status
[10:10:49.180] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:49.180] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:29:0) after 1m0s
[10:11:49.173] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:29:0)
[10:11:49.173] Timer tick, asking Buildbucket for the build status
[10:11:49.507] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:49.507] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:30:0) after 1m0s
[10:12:49.532] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:30:0)
[10:12:49.532] Timer tick, asking Buildbucket for the build status
[10:12:49.648] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:49.648] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:31:0) after 1m0s
[10:13:49.656] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:31:0)
[10:13:49.656] Timer tick, asking Buildbucket for the build status
[10:13:49.822] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:49.822] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:32:0) after 1m0s
[10:14:49.840] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:32:0)
[10:14:49.840] Timer tick, asking Buildbucket for the build status
[10:14:50.350] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:50.351] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:33:0) after 1m0s
[10:15:50.471] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:33:0)
[10:15:50.471] Timer tick, asking Buildbucket for the build status
[10:15:50.743] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:50.743] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:34:0) after 1m0s
[10:16:50.764] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:34:0)
[10:16:50.764] Timer tick, asking Buildbucket for the build status
[10:16:51.241] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:51.241] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:35:0) after 1m0s
[10:17:51.250] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:35:0)
[10:17:51.250] Timer tick, asking Buildbucket for the build status
[10:17:51.365] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:51.365] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:36:0) after 1m0s
[10:18:51.436] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:36:0)
[10:18:51.436] Timer tick, asking Buildbucket for the build status
[10:18:51.524] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:51.524] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:37:0) after 1m0s
[10:19:51.534] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:37:0)
[10:19:51.534] Timer tick, asking Buildbucket for the build status
[10:19:51.656] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:51.656] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:38:0) after 1m0s
[10:20:51.755] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:38:0)
[10:20:51.755] Timer tick, asking Buildbucket for the build status
[10:20:51.941] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:51.941] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:39:0) after 1m0s
[10:21:51.975] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:39:0)
[10:21:51.975] Timer tick, asking Buildbucket for the build status
[10:21:52.147] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:52.147] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:40:0) after 1m0s
[10:22:52.206] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:40:0)
[10:22:52.206] Timer tick, asking Buildbucket for the build status
[10:22:52.339] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:52.339] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:41:0) after 1m0s
[10:23:52.465] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:41:0)
[10:23:52.465] Timer tick, asking Buildbucket for the build status
[10:23:52.727] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:52.727] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:42:0) after 1m0s
[10:24:52.793] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:42:0)
[10:24:52.794] Timer tick, asking Buildbucket for the build status
[10:24:52.936] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:52.936] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:43:0) after 1m0s
[10:25:52.958] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:43:0)
[10:25:52.958] Timer tick, asking Buildbucket for the build status
[10:25:53.074] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:53.074] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:44:0) after 1m0s
[10:26:53.107] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:44:0)
[10:26:53.107] Timer tick, asking Buildbucket for the build status
[10:26:53.329] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:53.329] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:45:0) after 1m0s
[10:27:53.449] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:45:0)
[10:27:53.449] Timer tick, asking Buildbucket for the build status
[10:27:53.577] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:53.577] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:46:0) after 1m0s
[10:28:53.648] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:46:0)
[10:28:53.648] Timer tick, asking Buildbucket for the build status
[10:28:53.852] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:53.852] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:47:0) after 1m0s
[10:29:53.979] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:47:0)
[10:29:53.979] Timer tick, asking Buildbucket for the build status
[10:29:54.188] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:54.188] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:48:0) after 1m0s
[10:30:54.215] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:48:0)
[10:30:54.215] Timer tick, asking Buildbucket for the build status
[10:30:54.331] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:54.331] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:49:0) after 1m0s
[10:31:54.380] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:49:0)
[10:31:54.380] Timer tick, asking Buildbucket for the build status
[10:31:54.560] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:54.560] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:50:0) after 1m0s
[10:32:54.602] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:50:0)
[10:32:54.602] Timer tick, asking Buildbucket for the build status
[10:32:54.792] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:54.792] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:51:0) after 1m0s
[10:33:54.816] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:51:0)
[10:33:54.816] Timer tick, asking Buildbucket for the build status
[10:33:54.916] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:54.916] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:52:0) after 1m0s
[10:34:55.003] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:52:0)
[10:34:55.003] Timer tick, asking Buildbucket for the build status
[10:34:55.121] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:55.121] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:53:0) after 1m0s
[10:35:55.156] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:53:0)
[10:35:55.156] Timer tick, asking Buildbucket for the build status
[10:35:55.288] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:55.288] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:54:0) after 1m0s
[10:36:55.316] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:54:0)
[10:36:55.316] Timer tick, asking Buildbucket for the build status
[10:36:55.535] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:55.535] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:55:0) after 1m0s
[10:37:55.562] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:55:0)
[10:37:55.562] Timer tick, asking Buildbucket for the build status
[10:37:55.911] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:55.911] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:56:0) after 1m0s
[10:38:55.938] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:56:0)
[10:38:55.938] Timer tick, asking Buildbucket for the build status
[10:38:56.027] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:56.027] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:57:0) after 1m0s
[10:39:56.054] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:57:0)
[10:39:56.054] Timer tick, asking Buildbucket for the build status
[10:39:56.173] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:56.173] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:58:0) after 1m0s
[10:40:56.209] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:58:0)
[10:40:56.209] Timer tick, asking Buildbucket for the build status
[10:40:56.311] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:56.311] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:59:0) after 1m0s
[10:41:56.335] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:59:0)
[10:41:56.335] Timer tick, asking Buildbucket for the build status
[10:41:56.727] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:56.727] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:60:0) after 1m0s
[10:42:56.749] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:60:0)
[10:42:56.749] Timer tick, asking Buildbucket for the build status
[10:42:56.985] Build 8887673496615250016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:56.985] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053103559728206176:61:0) after 1m0s
[10:43:38.620] Received PubSub notification, asking Buildbucket for the build status
[10:43:38.703] Build 8887673496615250016: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:43:38.703] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1582454617674716",
"created_by": "project:chromium",
"created_ts": "1582451075881182",
"id": "8887673496615250016",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d4e866b3c27f7332f73f212f0a8927b56f9b2e90\"}}",
"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\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-central1-b-8-111-rc9r\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168921, \"got_angle_revision\": \"b5576b67bcf5ca9ed26590eb5c66b38526785583\", \"got_dawn_revision\": \"bc34d908f60d458c6d9e416a7404eac4703e1192\", \"got_nacl_revision\": \"530e1f0dae34020864c81e5cd52be54d9c656690\", \"got_revision\": \"d4e866b3c27f7332f73f212f0a8927b56f9b2e90\", \"got_revision_cp\": \"refs/heads/master@{#743829}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"847856089dc3f740f46f6f6af1be4c7cde50cdb2\", \"got_v8_revision\": \"a66f0bf66ea25ae7448b35fe74cef5642cdc0310\", \"got_v8_revision_cp\": \"refs/heads/8.2.153@{#1}\", \"got_webrtc_revision\": \"1dea1ea412021819bddbc9400dcca7d9dd2eba74\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30581}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d4e866b3c27f7332f73f212f0a8927b56f9b2e90\", \"swarm_hashes_refs/heads/master(at){#743829}_without_patch\": {\"accessibility_unittests\": \"e891ef6051fd94ed7df6da1423441988c9be2b89\", \"angle_unittests\": \"d0f9c487f170463d54dcb852729b014283a7f47b\", \"app_shell_unittests\": \"28309d7cc5b0aac2b91497b00982d0328cc47cc6\", \"aura_unittests\": \"05161b6ffccbb643346fcf6b5cdd4db51fc67e19\", \"base_unittests\": \"8c5283dba76a84b944e026dbb2d34bb746bb0f81\", \"base_util_unittests\": \"709893934b8402f706b2b9f51084a665ddbc7f60\", \"blink_common_unittests\": \"3a7a53fd1a5bae5d7fb24e2b003dbe81130bd97e\", \"blink_fuzzer_unittests\": \"de7a9fa637783f8e52d8ac3e8632a5d7999ea6b7\", \"blink_heap_unittests\": \"58058d90a5961423879bc0f344843e123da1345a\", \"blink_platform_unittests\": \"c49b343dd6b9978b74582413ae1939babd90dee3\", \"blink_python_tests\": \"b2c7b87826ece996cb7356e8344f3d9f7395e6f3\", \"blink_unittests\": \"90da0508eba1db410319788cebcb48b7722c411a\", \"blink_web_tests\": \"eb9d9e8e9a0083677c758071d7590620fa29dab8\", \"boringssl_crypto_tests\": \"232bcba2674d3dde52e8b95f9edb9c3717986d1a\", \"boringssl_ssl_tests\": \"3b880770546a25c043f4ebb7442c9bb8967f58a9\", \"browser_tests\": \"90507c39f5b5670e6af9c2bb47ca977fca9a8466\", \"cacheinvalidation_unittests\": \"a4cd3a038698aa743277926e0d66c071be443055\", \"capture_unittests\": \"b45c96ba278493248f71dc6021fbff73feed8973\", \"cast_unittests\": \"70d629ad40a0dd863659d5da4f2d919087cc6f73\", \"cc_unittests\": \"bf7ee62baaa49d561655dd2ba544c4a74f9b48cd\", \"chrome_app_unittests\": \"9cf92a91f21512103c2ec0ef4cd282d6c7418a65\", \"chromedriver_unittests\": \"04fc4e92039eccda48d5c338bb90fc8218925aea\", \"components_browsertests\": \"e6277ff74360d7750b69b132d134f920bc7837a6\", \"components_unittests\": \"11b4a237a99985a30231f9e54e020024961fac7a\", \"compositor_unittests\": \"084e3d03d0093aa8c4e065c9b8059ee0018a80d5\", \"content_browsertests\": \"8f5d9c8e0141ffae39610c1e59fa8b0a07ea1a11\", \"content_shell_crash_test\": \"f7b7b7ef7d149afbe0e5a171a61ff5d64137a17c\", \"content_unittests\": \"2357ab6f2f4f05469a39c353a5c62f422c94eb0f\", \"crashpad_tests\": \"9ce1e8e5ba1f7086cc641256213d63361fa3dabc\", \"cronet_tests\": \"26517ae7aa7592005067defc9865b277631038f6\", \"cronet_unittests\": \"dc217c5d6edc4bda6a62149f3ec0480493fda9a5\", \"crypto_unittests\": \"5e7b73d6a255dba63887844222ece64e420b81ca\", \"dbus_unittests\": \"b6ef333c362baf1b1764d441c1d3d300d3ab4b58\", \"device_unittests\": \"f3b229a08385de4f5421f66f42ae7ce953fcadc7\", \"display_unittests\": \"531fcd4edd01ec40ea9d2fc55928e25826a94f2e\", \"events_unittests\": \"bab3ee7683010c01f0bacd8a42fd9eef48934c35\", \"extensions_browsertests\": \"89fe9991f392350ca4c9dfb57e587ca91fa46d69\", \"extensions_unittests\": \"16e80cf5cdc173654b9c3e3eab2cea942d1e322b\", \"filesystem_service_unittests\": \"9d003874c5c30f0ccfb654745488af3a34a1685e\", \"flatbuffers_unittests\": \"c3db37cb8981f7422026864ae3bd0765e7fa7de4\", \"gcm_unit_tests\": \"e79e19ca3ceb8e6efa1100b46dfa1be9f281ad1a\", \"gfx_unittests\": \"af0f16cebe94ed6487a37b31a0300c11c77e915b\", \"gin_unittests\": \"c7ab40d90c3ab1cf902792d8e9ab218965279956\", \"google_apis_unittests\": \"6ebb5db461d4d5cd951d64a201b44f9f498e71a8\", \"gpu_unittests\": \"29002dd061f78e5e3e008ef2f38f79f6e7531a5c\", \"grit_python_unittests\": \"73624ac0238f8ac67cfd1de0fd5785fdae8fa44b\", \"gwp_asan_unittests\": \"6f7725e4d75b630c77a9c956713800485f9a89a9\", \"headless_browsertests\": \"cbc27269929946c6e6d53f78be81db47db0d1fd9\", \"headless_unittests\": \"11c90448f15c19b50300f968fcb50f40bfd9a673\", \"interactive_ui_tests\": \"06e418d77fd6edda9dd95a9384557401e5cae0c5\", \"ipc_tests\": \"2948173fe95d2ee23273f9a19c8f4f13decbe1ea\", \"jingle_unittests\": \"f855efaa979dde6b8fbe079f1863277541180f17\", \"latency_unittests\": \"65c142a698eef94a55e4ba5bb2bb3b1bafc40527\", \"libjingle_xmpp_unittests\": \"b7f144041ad979f4f74f4b0281b74e58d87f5137\", \"media_blink_unittests\": \"98a7ae53a30af22a92960b0dd43bad1c250c1b1d\", \"media_service_unittests\": \"d6f97caf5f5a424e62f634a500719f37c7b7c4d4\", \"media_unittests\": \"d5ce813ffaa429d35decd28270cfd680a11af783\", \"message_center_unittests\": \"9bc31c7eb3b6edeb8deea20d6b37e02a1cb63a85\", \"metrics_python_tests\": \"3e97b24f3418f11cf0a0b0740a5a7856509f653f\", \"midi_unittests\": \"13cb8e2d56b806f62df8dd11eb1ed3b79a96a624\", \"mojo_core_unittests\": \"63ed2c6f6ce4d4fd968577446ee56c6839a0ebef\", \"mojo_unittests\": \"659237645a4c19af7749946f2168b93846a7b92b\", \"nacl_helper_nonsfi_unittests\": \"3e0aad9eb8ba2cfd0c1b319ec5e035dd5d228563\", \"nacl_loader_unittests\": \"030cf37b00d9b16d3aa3cb21565908d8de66cec6\", \"native_theme_unittests\": \"7f962d7304ac18483b546122027033f1d22096ae\", \"net_unittests\": \"02334d2db1941f8e147e1e98e8930d5725c81d41\", \"pdf_unittests\": \"8b66136e2f4b1f895bbe73efa363839b261f8546\", \"perfetto_unittests\": \"8f6f8fd6863ce620bd86f1483a35fdecc747e756\", \"ppapi_unittests\": \"d2ae7a723b52957408a6877d7b2329829019968b\", \"printing_unittests\": \"d43517982d2e862a29471d7a8bf62fed11675a16\", \"remoting_unittests\": \"ca0d662f1822d36c2ab4b41b3eba7df50ad46bb5\", \"sandbox_linux_unittests\": \"ca20553c5ae59046999f62fa7c8871e80cde47f0\", \"service_manager_unittests\": \"6deb5d7014764f40b646c4d4be00888260087264\", \"services_unittests\": \"6661f961efff23e91a1deb6b1655e07a1c0cc3d9\", \"shell_dialogs_unittests\": \"0274008cbd8ca8c198706ab2d200458673e6302a\", \"skia_unittests\": \"2e393dd5a341564ed4d52904a20ff851038ebdaf\", \"snapshot_unittests\": \"845f9afd89a0eaa05960d4a6f968f7f394d02dfa\", \"sql_unittests\": \"893c4d0797e7a6cf34ececff680a31bce1c01975\", \"storage_unittests\": \"9ac5aa5d4df6dfb256900b29cf96ffd0e06ae7a6\", \"sync_integration_tests\": \"18e5f4750c6ff2e6d695002ffe12c26777841237\", \"telemetry_gpu_unittests\": \"7133435c55acf8484c7dc16681b6d201c63e6421\", \"telemetry_perf_unittests\": \"ae4677f17cf63f6b9d4603fb72a1357d9d812109\", \"telemetry_unittests\": \"c868a9fbedbfa7eb8fdb07029eb5008fa74bc594\", \"traffic_annotation_auditor_unittests\": \"1caac0566e24db3692f7275b5fcbe08ecbf1e331\", \"ui_base_unittests\": \"f69c94420dd3a050b6deea9d3aa4ca1b2e6f3d4f\", \"ui_touch_selection_unittests\": \"b1e9c59d2eb045ec131d281023020660e0fd560a\", \"unit_tests\": \"ffc80771c7be89c78cee869d8afe271177248986\", \"url_unittests\": \"e2fcbd01800d4940a664016b5e18833e7d93feb9\", \"views_perftests\": \"77edfe76262a98213aeb9aca7ee123950e5e6ea7\", \"views_unittests\": \"4d2fa4113f2ce610e5c82740b81198d51ac2611e\", \"viz_unittests\": \"3b76afc5c5f5210b41219057bc1c4545a3a2e660\", \"vr_common_unittests\": \"bb2766fd6e01a5ccd2e4d6479656a1e499c808be\", \"vr_pixeltests\": \"55bd4e6d77a07c0beb4116a3a42fdf17add5fd4f\", \"webdriver_wpt_tests\": \"e618a0335eceeafb735b47869527add35894ce56\", \"weblayer_browsertests\": \"ba93abe04264dcf95d56f44a99cf775643268c9c\", \"wm_unittests\": \"3f2ceccad935dbe48a9ff3c562ae3834f727e796\", \"wtf_unittests\": \"234443fa53214483d122c7d9fdba3b98dba36df2\", \"xr_browser_tests\": \"2661baec04de6c3eb7e385ccd1113d7e55219075\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_45c5b54513fd8d251ddf48af87bd70552077e6f51953669d04eaa4296b96a344_v2\", \"builder_4f073cad989671df25347297d55a43b2baed7923c7c8febe8eed874750cd540a_v2\", \"builder_5c21095a1a129131be723eb555273fa4f1dc89f7e6d0ed0ea2c09e97b0cf5fcb_v2\", \"builder_dbb86507a6c3fadd4cf4e1827f5f960c6207016e30a949100a00998d3583e722_v2\", \"builder_f72b88ee7914fe5eb8b32d979ad6b47003d3a0322a10970d43d78c86a32dd14e_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-central1-b-8-111-rc9r\"], \"image\": [\"chrome-xenial-20012600-8e1a7e485b7\"], \"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\": [\"4825-3d129d6\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1582451343413255",
"status": "COMPLETED",
"status_changed_ts": "1582454618164607",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168921",
"builder:Linux Builder (dbg)",
"buildset:commit/git/d4e866b3c27f7332f73f212f0a8927b56f9b2e90",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d4e866b3c27f7332f73f212f0a8927b56f9b2e90",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053103559728206176",
"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/8887673496615250016/+/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:4a8a40b7b1fbf410",
"user_agent:luci-scheduler"
],
"updated_ts": "1582454618178120",
"url": "https://ci.chromium.org/b/8887673496615250016",
"utcnow_ts": "1582454618693061"
}
}
[10:43:38.703] Invocation finished in 59m5.174177472s with status SUCCEEDED