[21:14:14.436] New invocation is queued and will start shortly
[21:14:15.532] Starting the invocation (attempt 1)
[21:14:15.591] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:14:15.591] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:14:15.591] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9061213895696640192",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0953fe07c43ab3e2d40a962b56e292591067a10a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9061213895696640192",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/0953fe07c43ab3e2d40a962b56e292591067a10a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0953fe07c43ab3e2d40a962b56e292591067a10a",
"gitiles_ref:refs/heads/master"
]
}
[21:14:17.749] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1574716456382897",
"id": "8895783832991145680",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0953fe07c43ab3e2d40a962b56e292591067a10a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1574716457515183",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/165277",
"builder:Linux Builder (dbg)",
"buildset:commit/git/0953fe07c43ab3e2d40a962b56e292591067a10a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0953fe07c43ab3e2d40a962b56e292591067a10a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9061213895696640192",
"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/8895783832991145680/+/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": "1574716457515300",
"url": "https://ci.chromium.org/b/8895783832991145680",
"utcnow_ts": "1574716457724078"
}
}
[21:14:17.749] Task URL: https://ci.chromium.org/b/8895783832991145680
[21:14:17.749] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:2:0) after 1m0s
[21:15:17.810] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:2:0)
[21:15:17.810] Timer tick, asking Buildbucket for the build status
[21:15:18.067] Build 8895783832991145680: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:15:18.067] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:3:0) after 1m0s
[21:16:18.194] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:3:0)
[21:16:18.194] Timer tick, asking Buildbucket for the build status
[21:16:18.744] Build 8895783832991145680: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:16:18.744] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:4:0) after 1m0s
[21:17:18.899] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:4:0)
[21:17:18.899] Timer tick, asking Buildbucket for the build status
[21:17:19.092] Build 8895783832991145680: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:17:19.092] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:5:0) after 1m0s
[21:18:19.182] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:5:0)
[21:18:19.182] Timer tick, asking Buildbucket for the build status
[21:18:19.447] Build 8895783832991145680: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[21:18:19.447] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:6:0) after 1m0s
[21:19:06.041] Received PubSub notification, asking Buildbucket for the build status
[21:19:06.287] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:19.754] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:6:0)
[21:19:19.754] Timer tick, asking Buildbucket for the build status
[21:19:20.085] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:20.085] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:8:0) after 1m0s
[21:20:20.250] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:8:0)
[21:20:20.250] Timer tick, asking Buildbucket for the build status
[21:20:20.476] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:20.476] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:9:0) after 1m0s
[21:21:20.544] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:9:0)
[21:21:20.544] Timer tick, asking Buildbucket for the build status
[21:21:20.748] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:20.748] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:10:0) after 1m0s
[21:22:20.812] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:10:0)
[21:22:20.812] Timer tick, asking Buildbucket for the build status
[21:22:21.157] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:21.157] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:11:0) after 1m0s
[21:23:21.255] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:11:0)
[21:23:21.255] Timer tick, asking Buildbucket for the build status
[21:23:21.522] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:21.522] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:12:0) after 1m0s
[21:24:21.620] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:12:0)
[21:24:21.620] Timer tick, asking Buildbucket for the build status
[21:24:22.003] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:22.003] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:13:0) after 1m0s
[21:25:22.165] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:13:0)
[21:25:22.165] Timer tick, asking Buildbucket for the build status
[21:25:22.351] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:22.351] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:14:0) after 1m0s
[21:26:22.841] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:14:0)
[21:26:22.842] Timer tick, asking Buildbucket for the build status
[21:26:23.079] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:23.079] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:15:0) after 1m0s
[21:27:23.508] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:15:0)
[21:27:23.508] Timer tick, asking Buildbucket for the build status
[21:27:24.294] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:24.294] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:16:0) after 1m0s
[21:28:24.365] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:16:0)
[21:28:24.365] Timer tick, asking Buildbucket for the build status
[21:28:25.394] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:25.394] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:17:0) after 1m0s
[21:29:25.443] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:17:0)
[21:29:25.443] Timer tick, asking Buildbucket for the build status
[21:29:25.785] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:25.785] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:18:0) after 1m0s
[21:30:26.035] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:18:0)
[21:30:26.035] Timer tick, asking Buildbucket for the build status
[21:30:26.272] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:26.272] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:19:0) after 1m0s
[21:31:26.325] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:19:0)
[21:31:26.325] Timer tick, asking Buildbucket for the build status
[21:31:26.598] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:26.598] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:20:0) after 1m0s
[21:32:26.611] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:20:0)
[21:32:26.611] Timer tick, asking Buildbucket for the build status
[21:32:28.319] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:28.319] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:21:0) after 1m0s
[21:33:28.503] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:21:0)
[21:33:28.503] Timer tick, asking Buildbucket for the build status
[21:33:28.721] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:28.721] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:22:0) after 1m0s
[21:34:28.867] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:22:0)
[21:34:28.867] Timer tick, asking Buildbucket for the build status
[21:34:29.186] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:29.186] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:23:0) after 1m0s
[21:35:29.363] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:23:0)
[21:35:29.363] Timer tick, asking Buildbucket for the build status
[21:35:29.603] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:29.603] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:24:0) after 1m0s
[21:36:29.752] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:24:0)
[21:36:29.752] Timer tick, asking Buildbucket for the build status
[21:36:30.360] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:30.360] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:25:0) after 1m0s
[21:37:30.471] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:25:0)
[21:37:30.471] Timer tick, asking Buildbucket for the build status
[21:37:30.645] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:30.645] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:26:0) after 1m0s
[21:38:30.910] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:26:0)
[21:38:30.910] Timer tick, asking Buildbucket for the build status
[21:38:31.619] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:31.619] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:27:0) after 1m0s
[21:39:32.121] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:27:0)
[21:39:32.121] Timer tick, asking Buildbucket for the build status
[21:39:32.506] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:32.506] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:28:0) after 1m0s
[21:40:32.634] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:28:0)
[21:40:32.634] Timer tick, asking Buildbucket for the build status
[21:40:34.625] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:34.625] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:29:0) after 1m0s
[21:41:34.707] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:29:0)
[21:41:34.707] Timer tick, asking Buildbucket for the build status
[21:41:34.969] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:34.969] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:30:0) after 1m0s
[21:42:35.084] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:30:0)
[21:42:35.084] Timer tick, asking Buildbucket for the build status
[21:42:35.336] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:35.336] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:31:0) after 1m0s
[21:43:35.647] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:31:0)
[21:43:35.647] Timer tick, asking Buildbucket for the build status
[21:43:36.686] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:36.686] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:32:0) after 1m0s
[21:44:36.864] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:32:0)
[21:44:36.864] Timer tick, asking Buildbucket for the build status
[21:44:38.451] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:38.451] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:33:0) after 1m0s
[21:45:38.580] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:33:0)
[21:45:38.580] Timer tick, asking Buildbucket for the build status
[21:45:39.458] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:39.458] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:34:0) after 1m0s
[21:46:39.523] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:34:0)
[21:46:39.523] Timer tick, asking Buildbucket for the build status
[21:46:41.379] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:41.379] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:35:0) after 1m0s
[21:47:41.423] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:35:0)
[21:47:41.423] Timer tick, asking Buildbucket for the build status
[21:47:41.672] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:41.672] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:36:0) after 1m0s
[21:48:41.816] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:36:0)
[21:48:41.816] Timer tick, asking Buildbucket for the build status
[21:48:42.320] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:42.320] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:37:0) after 1m0s
[21:49:42.502] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:37:0)
[21:49:42.502] Timer tick, asking Buildbucket for the build status
[21:49:42.974] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:42.974] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:38:0) after 1m0s
[21:50:43.552] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:38:0)
[21:50:43.552] Timer tick, asking Buildbucket for the build status
[21:50:43.725] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:43.725] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:39:0) after 1m0s
[21:51:43.898] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:39:0)
[21:51:43.898] Timer tick, asking Buildbucket for the build status
[21:51:44.323] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:44.323] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:40:0) after 1m0s
[21:52:44.492] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:40:0)
[21:52:44.492] Timer tick, asking Buildbucket for the build status
[21:52:44.685] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:44.685] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:41:0) after 1m0s
[21:53:44.802] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:41:0)
[21:53:44.802] Timer tick, asking Buildbucket for the build status
[21:53:45.404] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:45.404] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:42:0) after 1m0s
[21:54:45.622] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:42:0)
[21:54:45.622] Timer tick, asking Buildbucket for the build status
[21:54:45.849] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:45.849] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:43:0) after 1m0s
[21:55:45.996] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:43:0)
[21:55:45.996] Timer tick, asking Buildbucket for the build status
[21:55:46.163] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:46.163] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:44:0) after 1m0s
[21:56:46.295] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:44:0)
[21:56:46.295] Timer tick, asking Buildbucket for the build status
[21:56:46.532] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:46.532] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:45:0) after 1m0s
[21:57:46.680] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:45:0)
[21:57:46.680] Timer tick, asking Buildbucket for the build status
[21:57:46.969] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:46.969] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:46:0) after 1m0s
[21:58:47.046] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:46:0)
[21:58:47.047] Timer tick, asking Buildbucket for the build status
[21:58:47.262] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:47.262] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:47:0) after 1m0s
[21:59:47.314] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:47:0)
[21:59:47.314] Timer tick, asking Buildbucket for the build status
[21:59:48.272] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:48.272] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:48:0) after 1m0s
[22:00:48.598] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:48:0)
[22:00:48.598] Timer tick, asking Buildbucket for the build status
[22:00:48.849] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:48.849] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:49:0) after 1m0s
[22:01:49.000] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:49:0)
[22:01:49.000] Timer tick, asking Buildbucket for the build status
[22:01:49.776] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:49.776] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:50:0) after 1m0s
[22:02:49.802] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:50:0)
[22:02:49.802] Timer tick, asking Buildbucket for the build status
[22:02:50.033] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:50.033] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:51:0) after 1m0s
[22:03:50.123] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:51:0)
[22:03:50.123] Timer tick, asking Buildbucket for the build status
[22:03:51.696] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:51.696] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:52:0) after 1m0s
[22:04:51.695] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:52:0)
[22:04:51.695] Timer tick, asking Buildbucket for the build status
[22:04:51.909] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:51.909] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:53:0) after 1m0s
[22:05:52.001] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:53:0)
[22:05:52.001] Timer tick, asking Buildbucket for the build status
[22:05:52.411] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:52.411] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:54:0) after 1m0s
[22:06:52.506] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:54:0)
[22:06:52.506] Timer tick, asking Buildbucket for the build status
[22:06:53.017] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:53.017] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:55:0) after 1m0s
[22:07:53.047] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:55:0)
[22:07:53.047] Timer tick, asking Buildbucket for the build status
[22:07:54.737] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:54.737] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:56:0) after 1m0s
[22:08:54.787] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:56:0)
[22:08:54.787] Timer tick, asking Buildbucket for the build status
[22:08:55.040] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:55.040] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:57:0) after 1m0s
[22:09:55.057] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:57:0)
[22:09:55.057] Timer tick, asking Buildbucket for the build status
[22:09:55.797] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:55.798] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:58:0) after 1m0s
[22:10:55.863] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:58:0)
[22:10:55.863] Timer tick, asking Buildbucket for the build status
[22:10:56.082] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:56.082] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:59:0) after 1m0s
[22:11:56.123] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:59:0)
[22:11:56.123] Timer tick, asking Buildbucket for the build status
[22:11:56.716] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:56.716] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:60:0) after 1m0s
[22:12:56.749] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:60:0)
[22:12:56.749] Timer tick, asking Buildbucket for the build status
[22:12:57.067] Build 8895783832991145680: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:57.067] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061213895696640192:61:0) after 1m0s
[22:13:19.473] Received PubSub notification, asking Buildbucket for the build status
[22:13:19.820] Build 8895783832991145680: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:13:19.820] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1574719997787314",
"created_by": "project:chromium",
"created_ts": "1574716456382897",
"id": "8895783832991145680",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0953fe07c43ab3e2d40a962b56e292591067a10a\"}}",
"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-34-7cvm\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 165277, \"got_angle_revision\": \"34e5a96edc3ee51a607870d31fbfb9d10459df6f\", \"got_dawn_revision\": \"e9356045a4e020f4292cdd75fc636289322d17e2\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"0953fe07c43ab3e2d40a962b56e292591067a10a\", \"got_revision_cp\": \"refs/heads/master@{#718815}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"7b29abfc486e08c916efbb33a97be2e0a4d1ce9c\", \"got_v8_revision\": \"96a59c934ffc3b193ffeccb90ad315a48923cff8\", \"got_v8_revision_cp\": \"refs/heads/8.0.362@{#1}\", \"got_webrtc_revision\": \"f534a640477264e6414dc461c961c9212c48d8ea\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#29906}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0953fe07c43ab3e2d40a962b56e292591067a10a\", \"swarm_hashes_refs/heads/master(at){#718815}_without_patch\": {\"accessibility_unittests\": \"1b729587b58b836378951c774ce5317b62104975\", \"angle_unittests\": \"c21961e32dd2a90dfda359e4d84430eabfc864bc\", \"app_shell_unittests\": \"181560444ec7fc543355b66ff13c7e45cd92c13c\", \"aura_unittests\": \"96c9b70e07883477fdaafbb0310d5be3760256e7\", \"base_unittests\": \"fbf6b4130f83389ac4c903c4f69e894e692b2d2a\", \"base_util_unittests\": \"e6a499740be4947f245975973cc7f5f552be2aa1\", \"blink_common_unittests\": \"37bc05a5640635abb4e47298dae3332f81b4912c\", \"blink_fuzzer_unittests\": \"1b4cf3dbb529d3721bd8c5b233801bff53add693\", \"blink_heap_unittests\": \"a4eb8ae881f46a0bebff62bff474df5c652986ec\", \"blink_platform_unittests\": \"41622f4e6c9924ebe6f370d33fef2c0be1e05b9d\", \"blink_python_tests\": \"bd37c14d154bafbe0391d4ef238debc5988eaf58\", \"blink_unittests\": \"0d466ed0533570cd8b0a07cffee694369d4268a2\", \"blink_web_tests_exparchive\": \"7786a8e8362d1539c88995e07b7807a73e8500ab\", \"boringssl_crypto_tests\": \"9c75c516f6715ff810cc23b02f734d750aa80be6\", \"boringssl_ssl_tests\": \"ab08f29bc2a959566420ac0b9d9dda3ea8dd22d8\", \"browser_tests\": \"15cb7181faae957a64a481d42d8c4e56ed2893a2\", \"cacheinvalidation_unittests\": \"0916d91084f78205316ca283155413b07319074a\", \"capture_unittests\": \"20ddc842da00f79fc2a93b40f101878dab8c9bf7\", \"cast_unittests\": \"f38d90c1dd4430d6ea0e394aef25bd62d541bf47\", \"cc_unittests\": \"50077c5813cd5191785c1a6d2f691b3ab9f5177a\", \"chrome_app_unittests\": \"d9d18f5aa694399793b133328cf02fe435a08519\", \"chromedriver_unittests\": \"3730af768789b59a06cbe854dcd933c9b8ae0148\", \"color_unittests\": \"8219ca432c67d2f7c69cfe2a0678c87433ef6213\", \"components_browsertests\": \"ee67cb1649e8a03591b2f06f734846a782440598\", \"components_unittests\": \"3aae384c10cbcf479e5e1c0389b07d7ccd77c4d9\", \"compositor_unittests\": \"2edb71e2434a3bcd3a9d9e5dd1e9738091e404a3\", \"content_browsertests\": \"dae94013ed81487f690d2ad492de00d09c9f6d4d\", \"content_shell_crash_test\": \"c74be2af45ad7b0a9a7058a3070b5e2501ef61e2\", \"content_unittests\": \"90d866d63b7355ad580a231a752d82add0de7801\", \"crashpad_tests\": \"15aae9c3aab93c6c42b50d6269f2f5ef45695c07\", \"cronet_tests\": \"5552cb3769349e75a515ec6883e2414e01c56464\", \"cronet_unittests\": \"548a380ee663b05831440bfaf2d3d9f80d01f7b3\", \"crypto_unittests\": \"ba693c84199c6f911c6463966cf6431d676f1e82\", \"dbus_unittests\": \"657a8b925a137a0848c9423423497971e966d136\", \"device_unittests\": \"ae38a03e92cd415fb16c9ddd0fe5a0d749a1c30e\", \"devtools_lint_check\": \"e7642ad00dccf535555562cdcf8aa05d90c65ae0\", \"devtools_type_check\": \"acb2ea102c6f24e428df154faa5e3787767a51c3\", \"display_unittests\": \"5989763c5abedf4c7c1371e63ba073aeedef36ae\", \"events_unittests\": \"8697e2b46dca9ab0093263ff41db5b0ca4a16f80\", \"extensions_browsertests\": \"d266545e5b735c2b5a883237d6bc2c9e42b0a4d7\", \"extensions_unittests\": \"921caeaaba1941fd42cb7f33523083a7da5ef748\", \"filesystem_service_unittests\": \"411a02eaf85bd3fc4aa09262571b8ffa383209ae\", \"flatbuffers_unittests\": \"7789eafdfb4cdfa68fc1e31ec23b8fec028610f8\", \"gcm_unit_tests\": \"b94680c29af30c539da79d3ca19a7b4f59dc25ea\", \"gfx_unittests\": \"c1539aa4925959b05327f7342fd49fb1a547d8f5\", \"gin_unittests\": \"8bf3148d227f7387d5ea1cd6abb219ed59780ae2\", \"google_apis_unittests\": \"206bb7d2f8677dd3471bd38ebae27fc8962b576b\", \"gpu_unittests\": \"d5875602aa97e0cfded8ed6687a9587acb1c0457\", \"grit_python_unittests\": \"4267cdfe96cbb5881cc25cc5b15aab4b2f28c5f5\", \"gwp_asan_unittests\": \"5b5ef62dd386142e0f6343f573fe4ac64139a939\", \"headless_browsertests\": \"e30f3db38ccffeb166e0785de1eb3c51d2302c7a\", \"headless_unittests\": \"bce8a3119feb286a4622b9557cd1c2ed986cfda4\", \"interactive_ui_tests\": \"612c0a3456af719c1ed467bbc409dca280702afb\", \"ipc_tests\": \"e7fc2eb31f088bc57552e562d4f65913dbf81e66\", \"jingle_unittests\": \"96044ec477f53516856e958d3b23e0c56566a2a8\", \"latency_unittests\": \"bf5fd9effa9d67a107b28fa84f4c933d0a13a42d\", \"libjingle_xmpp_unittests\": \"b2a7204df0236d5f1208cdb362f1183f666aac72\", \"media_blink_unittests\": \"c57c2068ffa8a0458ac7112b7d997d24cf567f62\", \"media_service_unittests\": \"e8686e2772dd3e35a650c10d0d9cf5bac79bb0be\", \"media_unittests\": \"74ac3b650403bbec6164793615016e6cc77700a9\", \"message_center_unittests\": \"1b3838ab79058956b56bb64f372bd003389c7bfe\", \"metrics_python_tests\": \"06727d3391c202381d57ae1b0d017682b3aef019\", \"midi_unittests\": \"0a5a38a85576eaf070ea0214105049d26b5356c4\", \"mojo_core_unittests\": \"e19c00f4618fda3645147f363452999f6cb4f660\", \"mojo_unittests\": \"fd63e30307dd903529acf32ffd9c4808beca57fb\", \"nacl_helper_nonsfi_unittests\": \"f344b5d4babd9cd46e81c55b3b87a9dd4d929cbb\", \"nacl_loader_unittests\": \"6022baff48a75c8619f71f2251e45a265ec256ed\", \"native_theme_unittests\": \"21f4443b7031f60d5809b603f664218733b8c58d\", \"net_unittests\": \"30d8ec69abf6321203ce5bb27c76f831b2cf34eb\", \"pdf_unittests\": \"bd44abad25b1c2aa203036e7b138be052e71dc87\", \"perfetto_unittests\": \"33da5aff1a1807b8afce5c0b7bb18a41164a1330\", \"ppapi_unittests\": \"16bb9c5eff9cf5ab1e7d349d6ca6317a0ad7f4d3\", \"printing_unittests\": \"6df3c0164e7299b5bc765a240b24e867c9eb0ee8\", \"remoting_unittests\": \"356d8da1dbb3fb238364f625bd27c53e10621d74\", \"sandbox_linux_unittests\": \"5222174f58718d36b6be46d36ff482198e479072\", \"service_manager_unittests\": \"a2319d62da23912c5799efa88dbd0c455ad1ed63\", \"services_unittests\": \"e48641ef7ef1897a9c5614186fcaa01759f8a32b\", \"shell_dialogs_unittests\": \"bf30d3539f1ceb551f0206f237558cf07f55336c\", \"skia_unittests\": \"76a8665ee11607b92646f12b8d34e6c58d4098e7\", \"snapshot_unittests\": \"f24536ed48af59176a43cc03bb5e97878a9433a4\", \"sql_unittests\": \"9a408ae12992e8e9520aa27e594a56947c555785\", \"storage_unittests\": \"33da874cdbad347e7094e62ab8f31919a511cb69\", \"sync_integration_tests\": \"01fa5be9854ac5d2a917a717d88f8c73acaf3546\", \"telemetry_gpu_unittests\": \"0871f18a2a6697ee132518d2df71f8ec5fe8e777\", \"telemetry_perf_unittests\": \"43e7c6ee7dc0fb5e16a683fa28ba7bf2cf92e859\", \"telemetry_unittests\": \"16f86da36119ad8ca56383d43db8125ec2932f80\", \"traffic_annotation_auditor_unittests\": \"a3ea04742c01dd597691549dba278eeace490452\", \"ui_base_unittests\": \"7c5ecc9b2efad40e8b48afb03c472bfad24d39a3\", \"ui_touch_selection_unittests\": \"afdab9cc6986003d522f73b141e8e90eab22dc4d\", \"unit_tests\": \"da96719c68b11e1fd0445d9ba0a8af3c784ebc28\", \"url_unittests\": \"ad4ec3a83230c51d39f7659fd70bf5b52adda620\", \"views_perftests\": \"599ee45443537fa1eb84bee206f92d2a7e09f83e\", \"views_unittests\": \"a3e0b498a532f1b185ff71b2fa3919cec46b281a\", \"viz_unittests\": \"da171c6f04cc50fade511e079dacfbd0bffa1858\", \"vr_common_unittests\": \"1403ab074ca7bc6e4d570b1ba607cf63313b5064\", \"vr_pixeltests\": \"577464eb2ea4993d9baa7776554af0fab86950f1\", \"webdriver_wpt_tests\": \"01cae982c499631cdb15f8d13906857d755a7ac2\", \"weblayer_browsertests\": \"a47b11f8eacc9a433155e01da60ee03d85400563\", \"wm_unittests\": \"6fc688dfca286a94832e0856fc614127d118f6c0\", \"wtf_unittests\": \"7bdb5070f8531ab2476d1bd3e3f809d9c4c98e6e\", \"xr_browser_tests\": \"de2e210135a8d504dd2bdc828c37b6f80cc9f9a7\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_36472ddafb4960b563fa0dfd4883d4c097bf9805cea97d60ada20c6be0c80514_v2\", \"builder_5c30bb016a77fbc5ea3e6fb824c869c1bb0f4222c21aad0a92d459b086fbc996_v2\", \"builder_61faa0d0c1c079ecf670d8cfa4f78ad3b1687a5534233c7377462c3ee6819708_v2\", \"builder_8851890e994e29b56fba2042f90feba99073c8e56d0ea67af5ee13ebe9f87cf6_v2\", \"builder_97ff76ae173a9ce5e244c07639f8a3e6b9cacff7958414e43d82a5f30a20f3d2_v2\", \"builder_e4464922e5c60bddfcc3d01cfc511ef7f4a711bded636fa98c66f03a573264ee_v2\", \"builder_fa0031875b7f2f11c46443055ab400bfaeb07a1d2a142b58c631314016c04e0a_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-34-7cvm\"], \"image\": [\"chrome-xenial-19112000-160fdd580f1\"], \"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\": [\"4656-a09e2f8\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1574716744976568",
"status": "COMPLETED",
"status_changed_ts": "1574719998275272",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/165277",
"builder:Linux Builder (dbg)",
"buildset:commit/git/0953fe07c43ab3e2d40a962b56e292591067a10a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0953fe07c43ab3e2d40a962b56e292591067a10a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9061213895696640192",
"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/8895783832991145680/+/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:48bd3c0263e3aa10",
"user_agent:luci-scheduler"
],
"updated_ts": "1574719998433276",
"url": "https://ci.chromium.org/b/8895783832991145680",
"utcnow_ts": "1574719999807696"
}
}
[22:13:19.820] Invocation finished in 59m5.402179313s with status SUCCEEDED