[14:08:53.178] New invocation is queued and will start shortly
[14:08:54.850] Starting the invocation (attempt 1)
[14:08:54.929] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:08:54.929] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:08:54.929] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9054627078135873440",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d67dd3b425974c97df7e96580e5abb0be6d0b189\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9054627078135873440",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/d67dd3b425974c97df7e96580e5abb0be6d0b189",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d67dd3b425974c97df7e96580e5abb0be6d0b189",
"gitiles_ref:refs/heads/master"
]
}
[14:08:56.972] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1580998135273962",
"id": "8889197015265772624",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d67dd3b425974c97df7e96580e5abb0be6d0b189\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1580998135995748",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168304",
"builder:Linux Builder (dbg)",
"buildset:commit/git/d67dd3b425974c97df7e96580e5abb0be6d0b189",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d67dd3b425974c97df7e96580e5abb0be6d0b189",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054627078135873440",
"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/8889197015265772624/+/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": "1580998135995875",
"url": "https://ci.chromium.org/b/8889197015265772624",
"utcnow_ts": "1580998136960856"
}
}
[14:08:56.972] Task URL: https://ci.chromium.org/b/8889197015265772624
[14:08:56.972] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:2:0) after 1m0s
[14:09:57.050] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:2:0)
[14:09:57.050] Timer tick, asking Buildbucket for the build status
[14:09:57.409] Build 8889197015265772624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:09:57.409] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:3:0) after 1m0s
[14:10:57.472] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:3:0)
[14:10:57.472] Timer tick, asking Buildbucket for the build status
[14:10:57.649] Build 8889197015265772624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:10:57.649] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:4:0) after 1m0s
[14:11:57.671] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:4:0)
[14:11:57.671] Timer tick, asking Buildbucket for the build status
[14:11:57.808] Build 8889197015265772624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:11:57.808] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:5:0) after 1m0s
[14:12:57.877] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:5:0)
[14:12:57.877] Timer tick, asking Buildbucket for the build status
[14:12:58.018] Build 8889197015265772624: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:12:58.018] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:6:0) after 1m0s
[14:13:08.330] Received PubSub notification, asking Buildbucket for the build status
[14:13:08.476] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:58.147] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:6:0)
[14:13:58.147] Timer tick, asking Buildbucket for the build status
[14:13:58.993] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:58.993] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:8:0) after 1m0s
[14:14:59.093] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:8:0)
[14:14:59.093] Timer tick, asking Buildbucket for the build status
[14:14:59.597] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:59.597] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:9:0) after 1m0s
[14:15:59.671] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:9:0)
[14:15:59.671] Timer tick, asking Buildbucket for the build status
[14:16:00.148] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:00.148] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:10:0) after 1m0s
[14:17:00.229] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:10:0)
[14:17:00.229] Timer tick, asking Buildbucket for the build status
[14:17:00.413] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:00.413] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:11:0) after 1m0s
[14:18:00.484] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:11:0)
[14:18:00.484] Timer tick, asking Buildbucket for the build status
[14:18:00.611] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:00.611] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:12:0) after 1m0s
[14:19:00.632] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:12:0)
[14:19:00.632] Timer tick, asking Buildbucket for the build status
[14:19:01.384] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:01.384] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:13:0) after 1m0s
[14:20:01.480] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:13:0)
[14:20:01.480] Timer tick, asking Buildbucket for the build status
[14:20:02.438] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:02.438] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:14:0) after 1m0s
[14:21:02.521] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:14:0)
[14:21:02.521] Timer tick, asking Buildbucket for the build status
[14:21:02.673] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:02.673] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:15:0) after 1m0s
[14:22:02.729] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:15:0)
[14:22:02.729] Timer tick, asking Buildbucket for the build status
[14:22:02.980] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:02.980] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:16:0) after 1m0s
[14:23:03.014] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:16:0)
[14:23:03.014] Timer tick, asking Buildbucket for the build status
[14:23:03.231] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:03.231] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:17:0) after 1m0s
[14:24:03.235] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:17:0)
[14:24:03.235] Timer tick, asking Buildbucket for the build status
[14:24:03.475] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:03.475] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:18:0) after 1m0s
[14:25:03.516] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:18:0)
[14:25:03.516] Timer tick, asking Buildbucket for the build status
[14:25:06.343] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:06.343] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:19:0) after 1m0s
[14:26:06.362] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:19:0)
[14:26:06.362] Timer tick, asking Buildbucket for the build status
[14:26:06.765] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:06.765] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:20:0) after 1m0s
[14:27:06.884] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:20:0)
[14:27:06.884] Timer tick, asking Buildbucket for the build status
[14:27:07.031] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:07.031] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:21:0) after 1m0s
[14:28:07.116] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:21:0)
[14:28:07.116] Timer tick, asking Buildbucket for the build status
[14:28:07.265] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:07.265] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:22:0) after 1m0s
[14:29:07.352] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:22:0)
[14:29:07.352] Timer tick, asking Buildbucket for the build status
[14:29:07.551] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:07.551] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:23:0) after 1m0s
[14:30:07.593] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:23:0)
[14:30:07.593] Timer tick, asking Buildbucket for the build status
[14:30:07.749] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:07.749] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:24:0) after 1m0s
[14:31:07.819] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:24:0)
[14:31:07.819] Timer tick, asking Buildbucket for the build status
[14:31:08.482] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:08.482] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:25:0) after 1m0s
[14:32:08.513] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:25:0)
[14:32:08.513] Timer tick, asking Buildbucket for the build status
[14:32:08.855] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:08.855] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:26:0) after 1m0s
[14:33:08.877] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:26:0)
[14:33:08.877] Timer tick, asking Buildbucket for the build status
[14:33:09.344] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:09.344] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:27:0) after 1m0s
[14:34:09.391] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:27:0)
[14:34:09.391] Timer tick, asking Buildbucket for the build status
[14:34:09.772] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:09.772] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:28:0) after 1m0s
[14:35:09.831] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:28:0)
[14:35:09.831] Timer tick, asking Buildbucket for the build status
[14:35:11.606] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:11.606] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:29:0) after 1m0s
[14:36:11.647] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:29:0)
[14:36:11.647] Timer tick, asking Buildbucket for the build status
[14:36:12.484] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:12.484] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:30:0) after 1m0s
[14:37:12.578] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:30:0)
[14:37:12.578] Timer tick, asking Buildbucket for the build status
[14:37:12.717] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:12.717] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:31:0) after 1m0s
[14:38:12.937] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:31:0)
[14:38:12.937] Timer tick, asking Buildbucket for the build status
[14:38:14.464] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:14.464] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:32:0) after 1m0s
[14:39:14.561] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:32:0)
[14:39:14.561] Timer tick, asking Buildbucket for the build status
[14:39:14.689] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:14.689] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:33:0) after 1m0s
[14:40:14.809] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:33:0)
[14:40:14.809] Timer tick, asking Buildbucket for the build status
[14:40:14.987] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:14.987] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:34:0) after 1m0s
[14:41:15.027] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:34:0)
[14:41:15.027] Timer tick, asking Buildbucket for the build status
[14:41:15.197] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:15.197] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:35:0) after 1m0s
[14:42:15.254] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:35:0)
[14:42:15.254] Timer tick, asking Buildbucket for the build status
[14:42:15.431] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:15.431] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:36:0) after 1m0s
[14:43:15.440] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:36:0)
[14:43:15.440] Timer tick, asking Buildbucket for the build status
[14:43:15.588] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:15.588] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:37:0) after 1m0s
[14:44:15.888] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:37:0)
[14:44:15.888] Timer tick, asking Buildbucket for the build status
[14:44:16.572] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:16.572] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:38:0) after 1m0s
[14:45:16.867] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:38:0)
[14:45:16.867] Timer tick, asking Buildbucket for the build status
[14:45:17.114] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:17.114] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:39:0) after 1m0s
[14:46:17.267] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:39:0)
[14:46:17.267] Timer tick, asking Buildbucket for the build status
[14:46:17.707] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:17.707] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:40:0) after 1m0s
[14:47:17.765] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:40:0)
[14:47:17.765] Timer tick, asking Buildbucket for the build status
[14:47:18.295] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:18.295] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:41:0) after 1m0s
[14:48:18.412] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:41:0)
[14:48:18.412] Timer tick, asking Buildbucket for the build status
[14:48:18.727] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:18.727] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:42:0) after 1m0s
[14:49:18.795] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:42:0)
[14:49:18.795] Timer tick, asking Buildbucket for the build status
[14:49:18.928] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:18.928] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:43:0) after 1m0s
[14:50:18.960] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:43:0)
[14:50:18.960] Timer tick, asking Buildbucket for the build status
[14:50:19.194] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:19.195] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:44:0) after 1m0s
[14:51:19.259] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:44:0)
[14:51:19.259] Timer tick, asking Buildbucket for the build status
[14:51:19.437] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:19.437] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:45:0) after 1m0s
[14:52:19.518] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:45:0)
[14:52:19.518] Timer tick, asking Buildbucket for the build status
[14:52:19.689] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:19.689] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:46:0) after 1m0s
[14:53:19.767] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:46:0)
[14:53:19.767] Timer tick, asking Buildbucket for the build status
[14:53:20.469] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:20.469] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:47:0) after 1m0s
[14:54:20.597] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:47:0)
[14:54:20.597] Timer tick, asking Buildbucket for the build status
[14:54:20.767] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:20.767] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:48:0) after 1m0s
[14:55:20.927] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:48:0)
[14:55:20.927] Timer tick, asking Buildbucket for the build status
[14:55:21.115] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:21.115] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:49:0) after 1m0s
[14:56:21.327] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:49:0)
[14:56:21.327] Timer tick, asking Buildbucket for the build status
[14:56:21.494] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:21.494] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:50:0) after 1m0s
[14:57:21.543] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:50:0)
[14:57:21.543] Timer tick, asking Buildbucket for the build status
[14:57:21.691] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:21.691] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:51:0) after 1m0s
[14:58:21.711] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:51:0)
[14:58:21.711] Timer tick, asking Buildbucket for the build status
[14:58:21.875] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:21.875] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:52:0) after 1m0s
[14:59:21.918] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:52:0)
[14:59:21.918] Timer tick, asking Buildbucket for the build status
[14:59:22.083] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:22.083] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:53:0) after 1m0s
[15:00:22.169] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:53:0)
[15:00:22.169] Timer tick, asking Buildbucket for the build status
[15:00:22.843] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:22.843] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:54:0) after 1m0s
[15:01:22.959] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:54:0)
[15:01:22.959] Timer tick, asking Buildbucket for the build status
[15:01:23.371] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:23.371] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:55:0) after 1m0s
[15:02:23.467] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:55:0)
[15:02:23.467] Timer tick, asking Buildbucket for the build status
[15:02:23.705] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:23.705] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:56:0) after 1m0s
[15:03:23.783] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:56:0)
[15:03:23.783] Timer tick, asking Buildbucket for the build status
[15:03:24.062] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:24.062] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:57:0) after 1m0s
[15:04:24.112] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:57:0)
[15:04:24.113] Timer tick, asking Buildbucket for the build status
[15:04:25.219] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:25.219] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:58:0) after 1m0s
[15:05:25.383] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:58:0)
[15:05:25.383] Timer tick, asking Buildbucket for the build status
[15:05:25.590] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:25.590] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:59:0) after 1m0s
[15:06:25.667] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:59:0)
[15:06:25.667] Timer tick, asking Buildbucket for the build status
[15:06:26.423] Build 8889197015265772624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:26.423] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054627078135873440:60:0) after 1m0s
[15:06:45.523] Received PubSub notification, asking Buildbucket for the build status
[15:06:45.804] Build 8889197015265772624: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:06:45.804] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1581001604879906",
"created_by": "project:chromium",
"created_ts": "1580998135273962",
"id": "8889197015265772624",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d67dd3b425974c97df7e96580e5abb0be6d0b189\"}}",
"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-48-g548\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168304, \"got_angle_revision\": \"7572c967ba08ce22e2c25ec56932d7c47c83846a\", \"got_dawn_revision\": \"c30635174e125bda00771900089c34751e28faeb\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"d67dd3b425974c97df7e96580e5abb0be6d0b189\", \"got_revision_cp\": \"refs/heads/master@{#738945}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"dd48b7e2d06e74e1d20da5f43224b4ae99873e64\", \"got_v8_revision\": \"928314e9e9bdb39e11f1eae54daeb171379b505f\", \"got_v8_revision_cp\": \"refs/heads/8.2.18@{#1}\", \"got_webrtc_revision\": \"a9e10263044b7ef506f88d00605b3c97a23f684e\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30467}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d67dd3b425974c97df7e96580e5abb0be6d0b189\", \"swarm_hashes_refs/heads/master(at){#738945}_without_patch\": {\"accessibility_unittests\": \"e0dc1cf739392cce2565ad8e44541821306b59fd\", \"angle_unittests\": \"2b37da74655bf9b1a07fccbb504ebd1d0402b32c\", \"app_shell_unittests\": \"d2abf951d589ef7cb3c314d132ee30d3a24c548e\", \"aura_unittests\": \"2de77134e63b88f5114d3c242bd80e5c3c63ec71\", \"base_unittests\": \"36198e0771feb24b46c826f6bb7255d19614230a\", \"base_util_unittests\": \"906910cddb87f4755d82dd435d27747f6c1badb6\", \"blink_common_unittests\": \"d019bb8bd0fe089541adb94286c1ad6d32c19668\", \"blink_fuzzer_unittests\": \"c640ffbfb8d86c355fa212cb4517f9d32d05a7b8\", \"blink_heap_unittests\": \"afcc61a3646ee60e007686243b10788ee3e78c67\", \"blink_platform_unittests\": \"b68ff31bbad7d15a53688fe8b0718ae2c1faaf65\", \"blink_python_tests\": \"71cc84dd44612d4e34381c5653076c3fbfdf9f4d\", \"blink_unittests\": \"4f82f500242625aef75d84102210c4cd37a9309c\", \"blink_web_tests\": \"b69e02ecdafd92419217c04ee0fb50262b7d7c05\", \"boringssl_crypto_tests\": \"a10a169aa28122b4122bdeb0f64c700c8bf46507\", \"boringssl_ssl_tests\": \"bc5cd69267a8515d9e847f2b03d2067fdabc86ca\", \"browser_tests\": \"7e09343bd801d3da211ee03ee5c38bf7143f253b\", \"cacheinvalidation_unittests\": \"f2964eee9064f6349c1800d9577e0d91db078200\", \"capture_unittests\": \"e8af208c7b8ff2fc5c8a8c49c221ac3a8972ab2e\", \"cast_unittests\": \"8023b8473a5be1ea30a2c8ea1582b44bb8a76aea\", \"cc_unittests\": \"1eb5f5255f0e2689777a207ede1fc2ed20795309\", \"chrome_app_unittests\": \"8836a915aadcd408a0f1ef3e0fce3efd589dcae1\", \"chromedriver_unittests\": \"ca666a57230fa73fe0c0014d13116446aa393799\", \"components_browsertests\": \"79949009d531429a23b12f0da68ae92e8e133959\", \"components_unittests\": \"c82498d4a5603b531283f8ba60a68f990bc19de1\", \"compositor_unittests\": \"d3fca5e4f97876bdc835e2c8ca5ac1aca7847457\", \"content_browsertests\": \"e9319ad48b9b47d82e46f826855941766f53098e\", \"content_shell_crash_test\": \"dd5b3dd10084843378e2a5ee824647ded5f77a94\", \"content_unittests\": \"773f66cdabbf13c3d25bdf50d93468931a81cbfa\", \"crashpad_tests\": \"51b1d37b0fd48ea4473d84f994fb1d1db7c77bac\", \"cronet_tests\": \"2b6a606deb698587a8985265573b6f8615194271\", \"cronet_unittests\": \"f53c1f4106739e00b5a71df7c822f28a971af39e\", \"crypto_unittests\": \"da018043f50d9d3b7a233d656e6b612f675fb53e\", \"dbus_unittests\": \"7950e48e51f7f1989f606f49f7843e1395ef832d\", \"device_unittests\": \"498c9f7c8adbd55c88a298428bb78be3727edeb5\", \"display_unittests\": \"82a5b76748729ce82a758ecf63a794d60d3e0c36\", \"events_unittests\": \"b0076f601249ab6000aa0c0cb1e59f6b0c140c1f\", \"extensions_browsertests\": \"d5420328b181b843fa07650556a40e0ca50678e9\", \"extensions_unittests\": \"efc16590e6f95cb4e0a0d9f90d7ea587dafb2840\", \"filesystem_service_unittests\": \"0cf3d157d8a259c231e514645804148024469a34\", \"flatbuffers_unittests\": \"ca71348ce5934d53fb892115f8e6e8a2436d5e6c\", \"gcm_unit_tests\": \"896046dd358f34d498c3c4f94c1c9640743b0723\", \"gfx_unittests\": \"a05d382efb253f0fa57cfb665649238af4be4453\", \"gin_unittests\": \"618019b3877b524c1445382e04c8446174d8f203\", \"google_apis_unittests\": \"34288481cde14c2de27718e1c49a2350f272aa14\", \"gpu_unittests\": \"0101bfd715f18d87924e9d8cc895768c94a46be0\", \"grit_python_unittests\": \"aecc218d5b48c1b491833f4ad6734b0d3e8bb0c4\", \"gwp_asan_unittests\": \"d7fc741ea7c6bb3421d6034614fc16efa73dab04\", \"headless_browsertests\": \"319cfe3859530d6a3ced16a83e9916559871da7d\", \"headless_unittests\": \"5fa07d5ac4eead838a127ebf5286dd05fc5753e7\", \"interactive_ui_tests\": \"cc54a49578a818b46bc4d44d9b7f04e0f510dd7f\", \"ipc_tests\": \"233ea27f2de7ae067c009d01eceaf11317bd8b35\", \"jingle_unittests\": \"7fded8ef21ad0509400adfdcc2c824ef95aba7f1\", \"latency_unittests\": \"b3d64a561ee0a68253ca5fbf43efca6d2aa4ee2d\", \"libjingle_xmpp_unittests\": \"5bf223c3c0c4b3142a70ec7a851a86d6473345c5\", \"media_blink_unittests\": \"d1e11b497e7eb0538b68397f7c2194097b3ad375\", \"media_service_unittests\": \"7a1c9fd46683f6aa8d7bf49d907a1c6a5306c256\", \"media_unittests\": \"693a6cb912a105f123ffc823d0c9c86c6c9fa904\", \"message_center_unittests\": \"7e16d2fa7f6d52814a65ffb55bd603f212387876\", \"metrics_python_tests\": \"694ebeb7507a8cc666a9aa3e84f1ad386d441623\", \"midi_unittests\": \"48768a04acf50b187fca29d09a943e3649e58f79\", \"mojo_core_unittests\": \"aa18c7f183e2d8bdce03bd7e444209f6601b69d4\", \"mojo_unittests\": \"82c966627bbe7876b9e4849fa720b29377771aa9\", \"nacl_helper_nonsfi_unittests\": \"1245060376498636e2000814a0d299fcbc7fc830\", \"nacl_loader_unittests\": \"3030d0e312b367d9b9fe4e3c590d6979029d2310\", \"native_theme_unittests\": \"86417deb19787cce45d082320cb526ef8fc6e1bf\", \"net_unittests\": \"89de1186cf916fb5656fe418a3faf28d61325130\", \"pdf_unittests\": \"16ef80c1e49408d88c6d91a045fae3c281db9a85\", \"perfetto_unittests\": \"2201a21790dba4c3d09a32d21c7b388914345f20\", \"ppapi_unittests\": \"e8551f63bcc87749db68a8b6e67691c3016527bc\", \"printing_unittests\": \"8c6f72ac28f23a350e5b58f46f8ef4f409ace301\", \"remoting_unittests\": \"708bd9230703eb79ec98fbcdc088e9e1fd2b899c\", \"sandbox_linux_unittests\": \"d89303ddbeb387c2412b01385e70a65d4e4ea8bd\", \"service_manager_unittests\": \"644da382043cabebf052acf9443f130a113c489e\", \"services_unittests\": \"ac4c9575648272a1220b1642a1bd7a70c8674530\", \"shell_dialogs_unittests\": \"0d0a0c859a93045e7ade45528ff8863d1b8a6943\", \"skia_unittests\": \"0b30d2654e64387b609920d7a6a1df59613ed7aa\", \"snapshot_unittests\": \"9978fab5207d8b27aee391225ec0fb543cb311a1\", \"sql_unittests\": \"1847f8f72ab7a055794377234dd1bd991fb3c652\", \"storage_unittests\": \"54eadf060c133996a820be1d9d347d9bda24ab72\", \"sync_integration_tests\": \"195f8edb83c5dbbb54a51aef25e37f4fb0d893af\", \"telemetry_gpu_unittests\": \"a8d290fecbf516503bd49182ba35a38eb051c64d\", \"telemetry_perf_unittests\": \"19b0f91407df037095f344b32574c69d27b76255\", \"telemetry_unittests\": \"051394b7e16d3ddfb5acc6bf80c7265ec06c46e3\", \"traffic_annotation_auditor_unittests\": \"36e660fbc2ecf08337ceaf6b7413d0640f310add\", \"ui_base_unittests\": \"d1322d2ba8161e1953da8fed4a12b0ca2ee6659e\", \"ui_touch_selection_unittests\": \"2c450232fff5f8294418aec2e8098c1772d0119f\", \"unit_tests\": \"5382b52198a0195370fc1d1e591947bc4765bd8d\", \"url_unittests\": \"63fc71c4c79689dc09e4d372cfa577e65ad3b90e\", \"views_perftests\": \"69b90343b10a418ad660f8da0f14d9f49f31ff9d\", \"views_unittests\": \"fe39795478b8c03e6b8b7927902d739b849489c8\", \"viz_unittests\": \"44cb26a37064c4157ea4f1e85fb42db1189e08c5\", \"vr_common_unittests\": \"901336273ecf49fb59a021e59d8ae0090d36f1a1\", \"vr_pixeltests\": \"4d7775abcc8818a686b9715bef4b2bdeaf7052ae\", \"webdriver_wpt_tests\": \"1511f6aaac40d9e1150e4a337f1e04cddb64a6b4\", \"weblayer_browsertests\": \"08190b46b97f73f98a6b367d8eeb58f20bdb97c7\", \"wm_unittests\": \"84485478fedc0176f7b0805ef57ec37ed8b05457\", \"wtf_unittests\": \"3452328183122d0f6c2eb05405206572a73c431f\", \"xr_browser_tests\": \"6bbec0279d43ad1b615b480ff5e6c4065ddadb39\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0f0f2920b57dc7c027977ee87520a3ddbf7f87ddb2741f9160320f85fd7d4389_v2\", \"builder_1de787806eca61f7210aef74433a5aa46707bd25b3e4e26a6b86243e6768278e_v2\", \"builder_28bbe8e6e9c00f2d4cca6bb7518e2e3abcc6dba52c0db172294f96352b49589a_v2\", \"builder_387e931c7b633ee2f48b892df14288ffa2061feda74b30444810d565fa474120_v2\", \"builder_3d74f5cea4cbf91e4b26f92a522c5c74e61b738440ef29ba16f5c9a596a5b201_v2\", \"builder_97b15bccca8d35086c1bc50eecf0b53d98289cdc65be75a287efcc384d1f34ed_v2\", \"builder_b91573fd5c97bdd1870d9c2b7f3dee6525147840030fe1e0975f137bcc8950b1_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-48-g548\"], \"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\": [\"4809-bb2013c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1580998387414588",
"status": "COMPLETED",
"status_changed_ts": "1581001605144280",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168304",
"builder:Linux Builder (dbg)",
"buildset:commit/git/d67dd3b425974c97df7e96580e5abb0be6d0b189",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d67dd3b425974c97df7e96580e5abb0be6d0b189",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054627078135873440",
"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/8889197015265772624/+/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:4a33a69a0bdd5110",
"user_agent:luci-scheduler"
],
"updated_ts": "1581001605147741",
"url": "https://ci.chromium.org/b/8889197015265772624",
"utcnow_ts": "1581001605786080"
}
}
[15:06:45.804] Invocation finished in 57m52.650362226s with status SUCCEEDED