[17:08:28.524] New invocation is queued and will start shortly
[17:08:32.835] Starting the invocation (attempt 1)
[17:08:32.924] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:08:32.924] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:08:32.924] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9055159361162322992",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"7220e47a0e626ee15e1255a4672c20de2bd078b0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9055159361162322992",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/7220e47a0e626ee15e1255a4672c20de2bd078b0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7220e47a0e626ee15e1255a4672c20de2bd078b0",
"gitiles_ref:refs/heads/master"
]
}
[17:08:34.721] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1580490513396922",
"id": "8889729295383810496",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7220e47a0e626ee15e1255a4672c20de2bd078b0\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1580490514360967",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168086",
"builder:Linux Builder (dbg)",
"buildset:commit/git/7220e47a0e626ee15e1255a4672c20de2bd078b0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7220e47a0e626ee15e1255a4672c20de2bd078b0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9055159361162322992",
"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/8889729295383810496/+/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": "1580490514361107",
"url": "https://ci.chromium.org/b/8889729295383810496",
"utcnow_ts": "1580490514704846"
}
}
[17:08:34.721] Task URL: https://ci.chromium.org/b/8889729295383810496
[17:08:34.721] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:2:0) after 1m0s
[17:09:34.870] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:2:0)
[17:09:34.870] Timer tick, asking Buildbucket for the build status
[17:09:35.291] Build 8889729295383810496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:09:35.291] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:3:0) after 1m0s
[17:10:35.312] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:3:0)
[17:10:35.312] Timer tick, asking Buildbucket for the build status
[17:10:35.562] Build 8889729295383810496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:10:35.562] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:4:0) after 1m0s
[17:11:35.645] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:4:0)
[17:11:35.645] Timer tick, asking Buildbucket for the build status
[17:11:35.917] Build 8889729295383810496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:11:35.917] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:5:0) after 1m0s
[17:12:36.039] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:5:0)
[17:12:36.039] Timer tick, asking Buildbucket for the build status
[17:12:36.277] Build 8889729295383810496: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:12:36.277] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:6:0) after 1m0s
[17:13:07.261] Received PubSub notification, asking Buildbucket for the build status
[17:13:07.390] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:36.435] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:6:0)
[17:13:36.435] Timer tick, asking Buildbucket for the build status
[17:13:36.614] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:36.614] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:8:0) after 1m0s
[17:14:36.770] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:8:0)
[17:14:36.770] Timer tick, asking Buildbucket for the build status
[17:14:36.943] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:36.943] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:9:0) after 1m0s
[17:15:36.957] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:9:0)
[17:15:36.957] Timer tick, asking Buildbucket for the build status
[17:15:37.505] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:37.505] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:10:0) after 1m0s
[17:16:37.559] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:10:0)
[17:16:37.559] Timer tick, asking Buildbucket for the build status
[17:16:37.744] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:37.744] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:11:0) after 1m0s
[17:17:37.839] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:11:0)
[17:17:37.839] Timer tick, asking Buildbucket for the build status
[17:17:37.998] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:37.998] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:12:0) after 1m0s
[17:18:38.160] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:12:0)
[17:18:38.160] Timer tick, asking Buildbucket for the build status
[17:18:38.294] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:38.294] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:13:0) after 1m0s
[17:19:38.345] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:13:0)
[17:19:38.345] Timer tick, asking Buildbucket for the build status
[17:19:38.498] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:38.498] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:14:0) after 1m0s
[17:20:38.557] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:14:0)
[17:20:38.557] Timer tick, asking Buildbucket for the build status
[17:20:38.700] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:38.700] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:15:0) after 1m0s
[17:21:38.701] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:15:0)
[17:21:38.701] Timer tick, asking Buildbucket for the build status
[17:21:38.877] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:38.877] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:16:0) after 1m0s
[17:22:38.914] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:16:0)
[17:22:38.914] Timer tick, asking Buildbucket for the build status
[17:22:39.215] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:39.215] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:17:0) after 1m0s
[17:23:39.228] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:17:0)
[17:23:39.228] Timer tick, asking Buildbucket for the build status
[17:23:39.649] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:39.649] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:18:0) after 1m0s
[17:24:39.755] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:18:0)
[17:24:39.755] Timer tick, asking Buildbucket for the build status
[17:24:39.933] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:39.933] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:19:0) after 1m0s
[17:25:39.969] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:19:0)
[17:25:39.969] Timer tick, asking Buildbucket for the build status
[17:25:40.369] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:40.369] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:20:0) after 1m0s
[17:26:40.408] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:20:0)
[17:26:40.408] Timer tick, asking Buildbucket for the build status
[17:26:41.097] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:41.097] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:21:0) after 1m0s
[17:27:41.200] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:21:0)
[17:27:41.200] Timer tick, asking Buildbucket for the build status
[17:27:41.407] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:41.407] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:22:0) after 1m0s
[17:28:41.440] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:22:0)
[17:28:41.440] Timer tick, asking Buildbucket for the build status
[17:28:41.615] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:41.615] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:23:0) after 1m0s
[17:29:41.718] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:23:0)
[17:29:41.718] Timer tick, asking Buildbucket for the build status
[17:29:41.928] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:41.928] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:24:0) after 1m0s
[17:30:41.991] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:24:0)
[17:30:41.991] Timer tick, asking Buildbucket for the build status
[17:30:42.178] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:42.178] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:25:0) after 1m0s
[17:31:42.224] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:25:0)
[17:31:42.224] Timer tick, asking Buildbucket for the build status
[17:31:42.992] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:42.992] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:26:0) after 1m0s
[17:32:43.064] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:26:0)
[17:32:43.064] Timer tick, asking Buildbucket for the build status
[17:32:43.774] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:43.774] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:27:0) after 1m0s
[17:33:43.852] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:27:0)
[17:33:43.852] Timer tick, asking Buildbucket for the build status
[17:33:44.126] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:44.126] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:28:0) after 1m0s
[17:34:44.233] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:28:0)
[17:34:44.233] Timer tick, asking Buildbucket for the build status
[17:34:45.164] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:45.164] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:29:0) after 1m0s
[17:35:45.185] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:29:0)
[17:35:45.185] Timer tick, asking Buildbucket for the build status
[17:35:45.913] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:45.913] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:30:0) after 1m0s
[17:36:45.981] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:30:0)
[17:36:45.981] Timer tick, asking Buildbucket for the build status
[17:36:46.257] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:46.257] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:31:0) after 1m0s
[17:37:46.383] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:31:0)
[17:37:46.383] Timer tick, asking Buildbucket for the build status
[17:37:46.937] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:46.937] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:32:0) after 1m0s
[17:38:46.958] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:32:0)
[17:38:46.958] Timer tick, asking Buildbucket for the build status
[17:38:47.438] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:47.438] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:33:0) after 1m0s
[17:39:47.465] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:33:0)
[17:39:47.465] Timer tick, asking Buildbucket for the build status
[17:39:49.183] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:49.183] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:34:0) after 1m0s
[17:40:49.189] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:34:0)
[17:40:49.189] Timer tick, asking Buildbucket for the build status
[17:40:50.242] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:50.242] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:35:0) after 1m0s
[17:41:50.335] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:35:0)
[17:41:50.335] Timer tick, asking Buildbucket for the build status
[17:41:50.611] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:50.611] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:36:0) after 1m0s
[17:42:50.683] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:36:0)
[17:42:50.683] Timer tick, asking Buildbucket for the build status
[17:42:51.257] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:51.257] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:37:0) after 1m0s
[17:43:51.318] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:37:0)
[17:43:51.318] Timer tick, asking Buildbucket for the build status
[17:43:51.796] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:51.796] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:38:0) after 1m0s
[17:44:51.814] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:38:0)
[17:44:51.814] Timer tick, asking Buildbucket for the build status
[17:44:52.791] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:52.791] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:39:0) after 1m0s
[17:45:52.783] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:39:0)
[17:45:52.783] Timer tick, asking Buildbucket for the build status
[17:45:53.017] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:53.017] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:40:0) after 1m0s
[17:46:53.072] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:40:0)
[17:46:53.072] Timer tick, asking Buildbucket for the build status
[17:46:53.240] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:53.240] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:41:0) after 1m0s
[17:47:53.279] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:41:0)
[17:47:53.279] Timer tick, asking Buildbucket for the build status
[17:47:53.697] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:53.697] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:42:0) after 1m0s
[17:48:53.784] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:42:0)
[17:48:53.784] Timer tick, asking Buildbucket for the build status
[17:48:53.962] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:53.962] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:43:0) after 1m0s
[17:49:53.969] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:43:0)
[17:49:53.969] Timer tick, asking Buildbucket for the build status
[17:49:54.154] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:54.154] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:44:0) after 1m0s
[17:50:54.185] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:44:0)
[17:50:54.185] Timer tick, asking Buildbucket for the build status
[17:50:54.344] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:54.344] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:45:0) after 1m0s
[17:51:54.389] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:45:0)
[17:51:54.389] Timer tick, asking Buildbucket for the build status
[17:51:54.533] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:54.533] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:46:0) after 1m0s
[17:52:54.591] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:46:0)
[17:52:54.591] Timer tick, asking Buildbucket for the build status
[17:52:54.774] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:54.774] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:47:0) after 1m0s
[17:53:54.797] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:47:0)
[17:53:54.797] Timer tick, asking Buildbucket for the build status
[17:53:55.012] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:55.012] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:48:0) after 1m0s
[17:54:55.053] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:48:0)
[17:54:55.053] Timer tick, asking Buildbucket for the build status
[17:54:55.522] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:55.522] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:49:0) after 1m0s
[17:55:55.566] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:49:0)
[17:55:55.566] Timer tick, asking Buildbucket for the build status
[17:55:55.855] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:55.855] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:50:0) after 1m0s
[17:56:55.881] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:50:0)
[17:56:55.881] Timer tick, asking Buildbucket for the build status
[17:56:56.194] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:56.194] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:51:0) after 1m0s
[17:57:56.192] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:51:0)
[17:57:56.192] Timer tick, asking Buildbucket for the build status
[17:57:56.674] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:56.674] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:52:0) after 1m0s
[17:58:56.726] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:52:0)
[17:58:56.726] Timer tick, asking Buildbucket for the build status
[17:58:56.905] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:56.905] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:53:0) after 1m0s
[17:59:57.035] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:53:0)
[17:59:57.035] Timer tick, asking Buildbucket for the build status
[17:59:57.374] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:57.374] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:54:0) after 1m0s
[18:00:57.469] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:54:0)
[18:00:57.469] Timer tick, asking Buildbucket for the build status
[18:00:57.768] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:57.768] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:55:0) after 1m0s
[18:01:57.810] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:55:0)
[18:01:57.810] Timer tick, asking Buildbucket for the build status
[18:01:59.972] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:59.972] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:56:0) after 1m0s
[18:03:00.107] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:56:0)
[18:03:00.107] Timer tick, asking Buildbucket for the build status
[18:03:00.291] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:00.291] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:57:0) after 1m0s
[18:04:00.491] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:57:0)
[18:04:00.491] Timer tick, asking Buildbucket for the build status
[18:04:01.327] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:01.327] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:58:0) after 1m0s
[18:05:01.428] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:58:0)
[18:05:01.428] Timer tick, asking Buildbucket for the build status
[18:05:03.521] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:03.521] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:59:0) after 1m0s
[18:06:03.718] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:59:0)
[18:06:03.718] Timer tick, asking Buildbucket for the build status
[18:06:04.173] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:04.173] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:60:0) after 1m0s
[18:07:04.484] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:60:0)
[18:07:04.484] Timer tick, asking Buildbucket for the build status
[18:07:04.733] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:04.733] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:61:0) after 1m0s
[18:08:04.777] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:61:0)
[18:08:04.777] Timer tick, asking Buildbucket for the build status
[18:08:05.241] Build 8889729295383810496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:05.241] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055159361162322992:62:0) after 1m0s
[18:08:38.370] Received PubSub notification, asking Buildbucket for the build status
[18:08:39.451] Build 8889729295383810496: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:08:39.451] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1580494117426854",
"created_by": "project:chromium",
"created_ts": "1580490513396922",
"id": "8889729295383810496",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7220e47a0e626ee15e1255a4672c20de2bd078b0\"}}",
"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-83-76qr\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168086, \"got_angle_revision\": \"5de415c5e3f9616298f0d99d2f618e211870a8f2\", \"got_dawn_revision\": \"f44a809f9a3b6506df5cc1105198ed136c6b26ec\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"7220e47a0e626ee15e1255a4672c20de2bd078b0\", \"got_revision_cp\": \"refs/heads/master@{#737355}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"79b36b640692e3b4561642c06ec36a9e02a1636c\", \"got_v8_revision\": \"a4dcd39d521d14c4b1cac020812e44ee04a7f244\", \"got_v8_revision_cp\": \"refs/heads/8.1.307@{#1}\", \"got_webrtc_revision\": \"bfe3ef8feb3b4e351403be38fe626df4e796648c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30435}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7220e47a0e626ee15e1255a4672c20de2bd078b0\", \"swarm_hashes_refs/heads/master(at){#737355}_without_patch\": {\"accessibility_unittests\": \"91a33bd1ceff27f3b8bef4c888461512505b6c32\", \"angle_unittests\": \"200db9ab39c40328612dca64d3ec3e4dee2edf6d\", \"app_shell_unittests\": \"71fa1965f8902ece74996e763c3794045c8969db\", \"aura_unittests\": \"6e562bb6fb38ee4c3ff1b5d643bd817755b02742\", \"base_unittests\": \"b9ea70e9d2c33fe5539ba12f50fb824914ddfd77\", \"base_util_unittests\": \"402f7782bcf18cd349bc7bfd83b414c09ed1d510\", \"blink_common_unittests\": \"834f6820927ac3950ec305807527f439f5d27b75\", \"blink_fuzzer_unittests\": \"63bfef8a1f767cc30834aa511806e02a318bf727\", \"blink_heap_unittests\": \"fbd94c97c3f85bef3f1c10daa294558faf33f30e\", \"blink_platform_unittests\": \"e5a257e6e84553e86a0604ef1e91c3304ca91767\", \"blink_python_tests\": \"8aeee867c9b429d377b5d2fa08b91ca4739fc2dd\", \"blink_unittests\": \"602034005f8a68be31b617c7068446705931762e\", \"blink_web_tests\": \"1dbef3ce9bd9acfbd1b41e0cd72e6a1185bb7f94\", \"boringssl_crypto_tests\": \"8aaeee75fa2cdf2f91cee7452ba9d5c30fdde42f\", \"boringssl_ssl_tests\": \"6614251632f57a5ee07eba52bfd1f54aac1c5f7d\", \"browser_tests\": \"6aa38c24d6019d41d7c3be5abb1d640b0a73e021\", \"cacheinvalidation_unittests\": \"5f26140c14b5d29dac1b1300fd61e8bf015adaf7\", \"capture_unittests\": \"c8581663e22d6aec2e69023cd928c2da43bd55c3\", \"cast_unittests\": \"e33655afafb4c2f3d6802735dcdcec9a9b577c34\", \"cc_unittests\": \"d25b4eb78b27d5aab0d619922e14ee2af2532521\", \"chrome_app_unittests\": \"6142989fc27a1ffe341544798595d4af4ecd2c30\", \"chromedriver_unittests\": \"d2041deef611fb259d91830a589773b6fb02c75c\", \"components_browsertests\": \"05bea90ebb3436137200600b3b1a6d4812fc264a\", \"components_unittests\": \"25117d255e84209689aa261f0ed2bfcf12e96a50\", \"compositor_unittests\": \"08098a77da852cda4f34b94b99dab2da95619980\", \"content_browsertests\": \"6d6e11f24d74de145a9ca9ccdc9af54435bf8a43\", \"content_shell_crash_test\": \"83046f0d99073fd353973d62ba6b03665ebb53b4\", \"content_unittests\": \"b6d8e6d947a7bf58302af502ec267bb362e88dee\", \"crashpad_tests\": \"8e7df6c60a569dfae53d9de5f491416e6b54540f\", \"cronet_tests\": \"08b0a51f0af6f525c33e749f8db103090d364019\", \"cronet_unittests\": \"936ed918fd80ef96742e9c0d7250f746a543ccee\", \"crypto_unittests\": \"0401fa2d9936fc25347208a010fe013243636835\", \"dbus_unittests\": \"e98dc0a79e67d017dea376172443551133625961\", \"device_unittests\": \"6cc11a89d16531967068c69c4de9cecaaed7b9c0\", \"display_unittests\": \"eeaddb46d9e42543f3dbce02adebcdf370dc364d\", \"events_unittests\": \"3e2b9a3ecbe0800e39ade92daab1e16d5f437dc4\", \"extensions_browsertests\": \"14e1afde8e952e1275b8bcb70bd38d64db5b6296\", \"extensions_unittests\": \"b364257170b75f131c2b4f59c68e1087a22a4449\", \"filesystem_service_unittests\": \"dcccf7e65ef3c7a290268a9015ecd2d17df38f46\", \"flatbuffers_unittests\": \"ca71348ce5934d53fb892115f8e6e8a2436d5e6c\", \"gcm_unit_tests\": \"ae95e83af26f2c467e95a5aa2c03287e28ca33c6\", \"gfx_unittests\": \"e6a95b42e14100eea3dee121c1e6f4fde6a330ca\", \"gin_unittests\": \"5ae56b98a06ceb07c03c47897a51beb49fd345d8\", \"google_apis_unittests\": \"416bde27e70edb36ab7f5a91603f00cfdea58d27\", \"gpu_unittests\": \"424f98a4161aaa8f9abd5bc7d6bea0702d00597b\", \"grit_python_unittests\": \"a906de1211aa1e6d0b6deb8a9a162d6998fb6336\", \"gwp_asan_unittests\": \"a362a3716b50ced244322de62465f0feebd08155\", \"headless_browsertests\": \"4f4b593f79ed1348562e12bc04a6672dae551165\", \"headless_unittests\": \"fb535e8d831fa82b207fbd4f65afbb2fcc78aed0\", \"interactive_ui_tests\": \"f61fa3624a8adbd04404626ccda4b496f8090e72\", \"ipc_tests\": \"8a12985bcd52ed88473f734276bb6979607a73bd\", \"jingle_unittests\": \"2f2ca5e956682cd392a396d499e27a7803a59307\", \"latency_unittests\": \"c3563c770166972cfdb1090853c39bd3136a8f88\", \"libjingle_xmpp_unittests\": \"074b5b6a3cd7b05b63cda0fdb286305f110d3902\", \"media_blink_unittests\": \"1f0f2d0137b085756aff2afa0524f23aa1803438\", \"media_service_unittests\": \"7b88c99592155b702e83195393177aded2671d66\", \"media_unittests\": \"2ca2415c4de4b4384db549cb80f0fda61b6eccc9\", \"message_center_unittests\": \"625ba76be1ae4c0a0aa8247475d0462fb0f6b90a\", \"metrics_python_tests\": \"9cabc8b7825ed706f0a83463c191f04e11793acc\", \"midi_unittests\": \"f4d49c194734a02bd9e5902c90910e7bc06deb87\", \"mojo_core_unittests\": \"e76508d86f30a9189e2f24f2f8b0dae22b4f6b49\", \"mojo_unittests\": \"2ae49aa6271e950c05a3795af89db23d6cfb5adc\", \"nacl_helper_nonsfi_unittests\": \"e2b46562ac090958ce176ed9384d88d4981bdadf\", \"nacl_loader_unittests\": \"0157e9e78263be0bf57becb6cd4fc3e5e43ff2ac\", \"native_theme_unittests\": \"5215cc4c8a0fd055ab61a67b55be28080d5d3e33\", \"net_unittests\": \"79fac4940670ed5dd8bd1d3f6f578db8b0fde930\", \"pdf_unittests\": \"d6fbe5205c0ad2b1244826ec20c6de64683b9855\", \"perfetto_unittests\": \"bc6c4eac73cffdf335233b5a170c873c30228375\", \"ppapi_unittests\": \"cf5bf3bbed6b4193d0754812e3223a53b96a217b\", \"printing_unittests\": \"3a2aeb285ec55a280a7dfc7a560f553f481d84c1\", \"remoting_unittests\": \"151e346943b2e5fd1fbc8d68bdc0b4290e8b0942\", \"sandbox_linux_unittests\": \"133461b548e7b4bba5757d8397f65b7b2df26ba4\", \"service_manager_unittests\": \"e643c0bbb04ad18c96e53a2e391539a303b0dddc\", \"services_unittests\": \"67bb112f1ccf61f0ab35ddc4b243d717fda4ff58\", \"shell_dialogs_unittests\": \"153dcfc9fdecd1bdd3ef05e90bd945a55a13229e\", \"skia_unittests\": \"949154b84d7b4e4308a96014846a7b548a888843\", \"snapshot_unittests\": \"476dec697c9ac9891c6f2ea1dbe9c9abbd330473\", \"sql_unittests\": \"28ad4ab1cd662b5b0095f04e5c423d5fb4a5db5c\", \"storage_unittests\": \"0f876f797001089926ebd414eb288d8b05bb8625\", \"sync_integration_tests\": \"fd3a41f7f9162b5280fd4b7151aaaf9908770b39\", \"telemetry_gpu_unittests\": \"9c2a9a0eec9e9fdb71bd03beb3232ee5b3dfb165\", \"telemetry_perf_unittests\": \"30043cf852d9a63fb38fb41c629b7200153047ca\", \"telemetry_unittests\": \"74fbbefe3878f9447db2d4152b2dc554a64fa961\", \"traffic_annotation_auditor_unittests\": \"d5d491e869364c335847c6d4a3c1b441584ed030\", \"ui_base_unittests\": \"3020dfca18c57a4fce54116bc7d4eb8b72c192b2\", \"ui_touch_selection_unittests\": \"cee46b9b3df9ed9e546a9fcf9077029aa1b1813e\", \"unit_tests\": \"35c451847a7aa7abb2ac33bd879f86ea000fb654\", \"url_unittests\": \"73316f18f7477ecfaad95c4c6c2767e9ba26ac2d\", \"views_perftests\": \"015f8c256c05e66c7d96cb2aa23e317e75a11923\", \"views_unittests\": \"fd9960384107c5cc7d9bbde48dd6cb4b9d0c5e4d\", \"viz_unittests\": \"9f8e9e2ca2343a2822b65f3a184dc4cac1684f00\", \"vr_common_unittests\": \"745025b060eda8762bf5839c9dc06476e4765cca\", \"vr_pixeltests\": \"aad5839021c9e44f3beb822159b1affabd40174d\", \"webdriver_wpt_tests\": \"01b7f9cdd765e11bd468b41b9227af036fdf5183\", \"weblayer_browsertests\": \"c1e33c7cb4f2ebb33b94fa43d11e63f70dca41f0\", \"wm_unittests\": \"d38eaad26e1c6d40e3fc78eb6b401a17c5015cea\", \"wtf_unittests\": \"d58216da45c904faad106f170d45400214c3f255\", \"xr_browser_tests\": \"43d547730e303e8c6ca1d76d241f453421affe68\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_093e8a6336a6e6d4670d6f5128e7c73730afadf3bd7f9284d26d8f79182f8ce5_v2\", \"builder_4f073cad989671df25347297d55a43b2baed7923c7c8febe8eed874750cd540a_v2\", \"builder_5018db700e530f8e7ae860b5b3feab2b02d3dedf1bbd3e704c6c59fde1b308dd_v2\", \"builder_562e40d9a0016a631bf2d4b73416a93abb069b959f020aa1a03e04f54d5ec620_v2\", \"builder_7d75f6999c07e42a7f7fde2313383264fbd8ed871e2008bde33b278973c1f4c8_v2\", \"builder_8116c80c2882afcd67a910417bbd6fba6f94de5911d922ccd599199b9d07f827_v2\", \"builder_d78b729059d6d7166c1c60b6c63641496a1541bf700cb46d40203a280149f161_v2\", \"builder_dd987fcd94b516d81fcf1552cd73a9eed370c3dc02cc1a52ddee828f1ab8817d_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-83-76qr\"], \"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\": [\"4804-5c544e0\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1580490786350306",
"status": "COMPLETED",
"status_changed_ts": "1580494117780820",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168086",
"builder:Linux Builder (dbg)",
"buildset:commit/git/7220e47a0e626ee15e1255a4672c20de2bd078b0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7220e47a0e626ee15e1255a4672c20de2bd078b0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9055159361162322992",
"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/8889729295383810496/+/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:4a1564eaf48d4010",
"user_agent:luci-scheduler"
],
"updated_ts": "1580494117785641",
"url": "https://ci.chromium.org/b/8889729295383810496",
"utcnow_ts": "1580494119434098"
}
}
[18:08:39.451] Invocation finished in 1h0m10.950057641s with status SUCCEEDED