[22:06:45.191] New invocation is queued and will start shortly
[22:06:46.449] Starting the invocation (attempt 1)
[22:06:46.570] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:06:46.570] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:06:46.570] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9051607313454060368",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"dd589029655574e7c6b4bc82b8dc597ae815e4e0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9051607313454060368",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/dd589029655574e7c6b4bc82b8dc597ae815e4e0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dd589029655574e7c6b4bc82b8dc597ae815e4e0",
"gitiles_ref:refs/heads/master"
]
}
[22:06:47.390] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1583878006771398",
"id": "8886177251130358080",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dd589029655574e7c6b4bc82b8dc597ae815e4e0\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1583878007251194",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/169583",
"builder:Linux Builder (dbg)",
"buildset:commit/git/dd589029655574e7c6b4bc82b8dc597ae815e4e0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dd589029655574e7c6b4bc82b8dc597ae815e4e0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9051607313454060368",
"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/8886177251130358080/+/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": "1583878007251365",
"url": "https://ci.chromium.org/b/8886177251130358080",
"utcnow_ts": "1583878007381586"
}
}
[22:06:47.390] Task URL: https://ci.chromium.org/b/8886177251130358080
[22:06:47.391] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:2:0) after 1m0s
[22:07:47.472] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:2:0)
[22:07:47.473] Timer tick, asking Buildbucket for the build status
[22:07:47.785] Build 8886177251130358080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:07:47.785] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:3:0) after 1m0s
[22:08:47.851] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:3:0)
[22:08:47.851] Timer tick, asking Buildbucket for the build status
[22:08:48.072] Build 8886177251130358080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:08:48.072] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:4:0) after 1m0s
[22:09:48.093] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:4:0)
[22:09:48.093] Timer tick, asking Buildbucket for the build status
[22:09:48.209] Build 8886177251130358080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:09:48.209] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:5:0) after 1m0s
[22:10:48.283] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:5:0)
[22:10:48.283] Timer tick, asking Buildbucket for the build status
[22:10:48.452] Build 8886177251130358080: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:10:48.452] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:6:0) after 1m0s
[22:11:08.638] Received PubSub notification, asking Buildbucket for the build status
[22:11:08.881] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:48.476] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:6:0)
[22:11:48.476] Timer tick, asking Buildbucket for the build status
[22:11:48.562] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:48.562] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:8:0) after 1m0s
[22:12:48.563] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:8:0)
[22:12:48.563] Timer tick, asking Buildbucket for the build status
[22:12:48.734] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:48.734] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:9:0) after 1m0s
[22:13:48.826] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:9:0)
[22:13:48.826] Timer tick, asking Buildbucket for the build status
[22:13:48.928] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:48.928] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:10:0) after 1m0s
[22:14:49.005] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:10:0)
[22:14:49.005] Timer tick, asking Buildbucket for the build status
[22:14:49.102] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:49.102] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:11:0) after 1m0s
[22:15:49.147] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:11:0)
[22:15:49.147] Timer tick, asking Buildbucket for the build status
[22:15:49.299] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:49.299] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:12:0) after 1m0s
[22:16:49.354] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:12:0)
[22:16:49.354] Timer tick, asking Buildbucket for the build status
[22:16:49.514] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:49.514] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:13:0) after 1m0s
[22:17:49.618] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:13:0)
[22:17:49.618] Timer tick, asking Buildbucket for the build status
[22:17:49.769] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:49.769] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:14:0) after 1m0s
[22:18:49.854] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:14:0)
[22:18:49.854] Timer tick, asking Buildbucket for the build status
[22:18:50.060] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:50.060] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:15:0) after 1m0s
[22:19:50.111] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:15:0)
[22:19:50.111] Timer tick, asking Buildbucket for the build status
[22:19:50.372] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:50.372] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:16:0) after 1m0s
[22:20:50.365] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:16:0)
[22:20:50.365] Timer tick, asking Buildbucket for the build status
[22:20:50.491] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:50.491] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:17:0) after 1m0s
[22:21:50.554] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:17:0)
[22:21:50.554] Timer tick, asking Buildbucket for the build status
[22:21:50.700] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:50.700] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:18:0) after 1m0s
[22:22:50.757] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:18:0)
[22:22:50.757] Timer tick, asking Buildbucket for the build status
[22:22:50.872] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:50.872] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:19:0) after 1m0s
[22:23:51.001] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:19:0)
[22:23:51.001] Timer tick, asking Buildbucket for the build status
[22:23:51.125] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:51.125] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:20:0) after 1m0s
[22:24:51.107] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:20:0)
[22:24:51.107] Timer tick, asking Buildbucket for the build status
[22:24:51.252] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:51.252] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:21:0) after 1m0s
[22:25:51.291] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:21:0)
[22:25:51.291] Timer tick, asking Buildbucket for the build status
[22:25:51.648] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:51.648] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:22:0) after 1m0s
[22:26:51.759] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:22:0)
[22:26:51.759] Timer tick, asking Buildbucket for the build status
[22:26:52.076] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:52.076] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:23:0) after 1m0s
[22:27:52.139] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:23:0)
[22:27:52.139] Timer tick, asking Buildbucket for the build status
[22:27:52.556] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:52.556] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:24:0) after 1m0s
[22:28:52.747] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:24:0)
[22:28:52.747] Timer tick, asking Buildbucket for the build status
[22:28:52.853] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:52.853] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:25:0) after 1m0s
[22:29:53.353] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:25:0)
[22:29:53.353] Timer tick, asking Buildbucket for the build status
[22:29:53.489] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:53.489] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:26:0) after 1m0s
[22:30:53.624] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:26:0)
[22:30:53.624] Timer tick, asking Buildbucket for the build status
[22:30:53.733] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:53.733] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:27:0) after 1m0s
[22:31:53.788] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:27:0)
[22:31:53.788] Timer tick, asking Buildbucket for the build status
[22:31:53.915] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:53.915] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:28:0) after 1m0s
[22:32:54.018] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:28:0)
[22:32:54.018] Timer tick, asking Buildbucket for the build status
[22:32:54.247] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:54.247] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:29:0) after 1m0s
[22:33:54.389] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:29:0)
[22:33:54.389] Timer tick, asking Buildbucket for the build status
[22:33:54.552] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:54.552] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:30:0) after 1m0s
[22:34:54.544] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:30:0)
[22:34:54.544] Timer tick, asking Buildbucket for the build status
[22:34:54.653] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:54.653] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:31:0) after 1m0s
[22:35:54.933] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:31:0)
[22:35:54.933] Timer tick, asking Buildbucket for the build status
[22:35:55.046] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:55.046] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:32:0) after 1m0s
[22:36:55.093] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:32:0)
[22:36:55.093] Timer tick, asking Buildbucket for the build status
[22:36:55.289] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:55.289] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:33:0) after 1m0s
[22:37:55.407] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:33:0)
[22:37:55.407] Timer tick, asking Buildbucket for the build status
[22:37:55.532] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:55.532] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:34:0) after 1m0s
[22:38:55.672] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:34:0)
[22:38:55.672] Timer tick, asking Buildbucket for the build status
[22:38:55.802] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:55.802] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:35:0) after 1m0s
[22:39:55.814] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:35:0)
[22:39:55.814] Timer tick, asking Buildbucket for the build status
[22:39:55.938] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:55.938] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:36:0) after 1m0s
[22:40:56.065] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:36:0)
[22:40:56.065] Timer tick, asking Buildbucket for the build status
[22:40:56.157] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:56.157] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:37:0) after 1m0s
[22:41:56.252] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:37:0)
[22:41:56.252] Timer tick, asking Buildbucket for the build status
[22:41:56.599] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:56.599] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:38:0) after 1m0s
[22:42:56.676] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:38:0)
[22:42:56.676] Timer tick, asking Buildbucket for the build status
[22:42:56.734] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:56.734] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:39:0) after 1m0s
[22:43:56.760] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:39:0)
[22:43:56.760] Timer tick, asking Buildbucket for the build status
[22:43:56.958] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:56.958] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:40:0) after 1m0s
[22:44:56.955] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:40:0)
[22:44:56.955] Timer tick, asking Buildbucket for the build status
[22:44:57.037] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:57.037] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:41:0) after 1m0s
[22:45:57.247] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:41:0)
[22:45:57.247] Timer tick, asking Buildbucket for the build status
[22:45:59.487] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:59.487] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:42:0) after 1m0s
[22:46:59.582] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:42:0)
[22:46:59.582] Timer tick, asking Buildbucket for the build status
[22:46:59.710] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:59.711] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:43:0) after 1m0s
[22:47:59.813] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:43:0)
[22:47:59.813] Timer tick, asking Buildbucket for the build status
[22:47:59.942] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:59.942] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:44:0) after 1m0s
[22:49:00.013] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:44:0)
[22:49:00.013] Timer tick, asking Buildbucket for the build status
[22:49:00.256] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:00.256] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:45:0) after 1m0s
[22:50:00.432] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:45:0)
[22:50:00.432] Timer tick, asking Buildbucket for the build status
[22:50:00.617] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:00.617] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:46:0) after 1m0s
[22:51:00.630] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:46:0)
[22:51:00.630] Timer tick, asking Buildbucket for the build status
[22:51:00.785] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:00.785] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:47:0) after 1m0s
[22:52:00.876] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:47:0)
[22:52:00.876] Timer tick, asking Buildbucket for the build status
[22:52:01.157] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:01.157] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:48:0) after 1m0s
[22:53:01.219] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:48:0)
[22:53:01.219] Timer tick, asking Buildbucket for the build status
[22:53:01.592] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:01.592] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:49:0) after 1m0s
[22:54:01.668] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:49:0)
[22:54:01.668] Timer tick, asking Buildbucket for the build status
[22:54:01.802] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:01.802] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:50:0) after 1m0s
[22:55:01.841] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:50:0)
[22:55:01.841] Timer tick, asking Buildbucket for the build status
[22:55:02.210] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:02.211] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:51:0) after 1m0s
[22:56:02.306] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:51:0)
[22:56:02.306] Timer tick, asking Buildbucket for the build status
[22:56:02.419] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:02.419] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:52:0) after 1m0s
[22:57:02.420] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:52:0)
[22:57:02.420] Timer tick, asking Buildbucket for the build status
[22:57:02.564] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:02.564] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:53:0) after 1m0s
[22:58:02.678] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:53:0)
[22:58:02.678] Timer tick, asking Buildbucket for the build status
[22:58:02.857] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:02.857] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:54:0) after 1m0s
[22:59:03.191] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:54:0)
[22:59:03.191] Timer tick, asking Buildbucket for the build status
[22:59:03.502] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:03.503] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:55:0) after 1m0s
[23:00:03.601] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:55:0)
[23:00:03.601] Timer tick, asking Buildbucket for the build status
[23:00:03.766] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:03.766] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:56:0) after 1m0s
[23:01:03.825] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:56:0)
[23:01:03.825] Timer tick, asking Buildbucket for the build status
[23:01:03.946] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:03.946] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:57:0) after 1m0s
[23:02:04.050] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:57:0)
[23:02:04.050] Timer tick, asking Buildbucket for the build status
[23:02:04.154] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:04.154] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:58:0) after 1m0s
[23:03:04.325] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:58:0)
[23:03:04.325] Timer tick, asking Buildbucket for the build status
[23:03:04.427] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:04.427] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:59:0) after 1m0s
[23:04:04.553] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:59:0)
[23:04:04.553] Timer tick, asking Buildbucket for the build status
[23:04:04.683] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:04.683] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:60:0) after 1m0s
[23:05:04.866] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:60:0)
[23:05:04.866] Timer tick, asking Buildbucket for the build status
[23:05:04.965] Build 8886177251130358080: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:04.965] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9051607313454060368:61:0) after 1m0s
[23:05:54.687] Received PubSub notification, asking Buildbucket for the build status
[23:05:54.796] Build 8886177251130358080: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:05:54.796] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1583881553753593",
"created_by": "project:chromium",
"created_ts": "1583878006771398",
"id": "8886177251130358080",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dd589029655574e7c6b4bc82b8dc597ae815e4e0\"}}",
"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-east1-d-8-72-19hg\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 169583, \"got_angle_revision\": \"4f10b113a1914ea554eac86855eec5fd0a78e30e\", \"got_dawn_revision\": \"52d0627d5607cf191485165fe515062c2a61ce81\", \"got_nacl_revision\": \"35bcc9f6d5e81c3c39ad57de97ef9cd22301ee9e\", \"got_revision\": \"dd589029655574e7c6b4bc82b8dc597ae815e4e0\", \"got_revision_cp\": \"refs/heads/master@{#748922}\", \"got_swarming_client_revision\": \"cc958279ffd6853e0a1b227a7e957ca334fe56af\", \"got_swiftshader_revision\": \"8be72df6046231eedbd14d155a5a9484bda217c1\", \"got_v8_revision\": \"1003942d85ac1ace40dac7aecf518c4f108d4273\", \"got_v8_revision_cp\": \"refs/heads/8.2.289@{#1}\", \"got_webrtc_revision\": \"dd14a955967b69bf1611ce2985a5457934b1fb1d\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30744}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dd589029655574e7c6b4bc82b8dc597ae815e4e0\", \"swarm_hashes_refs/heads/master(at){#748922}_without_patch\": {\"accessibility_unittests\": \"6c558821b79482d4f2d18a5740a3213e7f7378d0\", \"angle_unittests\": \"9d3e3bac72e7359cee615c669a07f8bb528e8bb4\", \"app_shell_unittests\": \"3f9850a013c2a27288bb09aac5a97f01e4acebeb\", \"aura_unittests\": \"68b6bd4950c1b24c665eb000505ac5f313a93aa8\", \"base_unittests\": \"2def1b52a5e4df1ccd5c869332d77cce5ac61fbe\", \"base_util_unittests\": \"0a3bd143dd3f68ea313b61ec01e5507d8eab4f57\", \"blink_common_unittests\": \"8ba3bf7143c4361760c20a0a67b5fa895d3df1e7\", \"blink_fuzzer_unittests\": \"6d6aa4913f2796d7c21f41b287e26984a267c26f\", \"blink_heap_unittests\": \"ee6d3f064054f23ccd218eaf1e50d27a4c64c53f\", \"blink_platform_unittests\": \"2bec6e1ea2a9de847143b2177837420d6ccda372\", \"blink_python_tests\": \"e504bd39f965def0cb88a6809a96385932ce36c7\", \"blink_unittests\": \"e7e0ab12f93b701aa1b58b8b967f0ad07bcaea64\", \"blink_web_tests\": \"4313443ba113d8794efb8f6daed4f7308e79b5ab\", \"boringssl_crypto_tests\": \"45d85a76bdeacccdae80202e6085d18b6a5c8c73\", \"boringssl_ssl_tests\": \"e8ec41a00f24da9ddf546004f6144bf78dc5a5fe\", \"browser_tests\": \"25f9402187246563c3556147bb10d39383e16ba5\", \"cacheinvalidation_unittests\": \"dbd51c154b6de12c0031747b3791560d160fc5cd\", \"capture_unittests\": \"d57c4e91ea47ea990ad2aa0202f5ba3fb12209fd\", \"cast_unittests\": \"d307c5cab3724b7837bd1adcc434e69f162234bf\", \"cc_unittests\": \"9946a376614ea6a44830e7d96e3a42b09c7ed082\", \"chrome_app_unittests\": \"637d706dda5a41777db9ba94c8f38b9cc5f1f6d4\", \"chromedriver_unittests\": \"81a7bbba914cb2a5c91873e7b837e03891ab24db\", \"components_browsertests\": \"73dfb6712391a9eae635ace0c893820d50efef88\", \"components_unittests\": \"8ebe7c9bfa8a8304b17338ed4e3a5d7467622ec0\", \"compositor_unittests\": \"681560b26aa2234dfcb803842bc9428c93d89a7a\", \"content_browsertests\": \"1993d4003036aaeecaa40065be2ed23123a5586c\", \"content_shell_crash_test\": \"142b9e6f683295b3021cffb8df80cdfd91bc66d9\", \"content_unittests\": \"66124c60fc468db00ccf2e113e0dbf846fdbd1f2\", \"crashpad_tests\": \"77188be690e0aff478ab8b4aacad17d99204a019\", \"cronet_tests\": \"51616492e591011d0dc118cf9c094dbfa342af0f\", \"cronet_unittests\": \"87b9d4cffae7636bb096f1d98738ee3daa977c20\", \"crypto_unittests\": \"8a1c9d69c3159a82457fc7564fdc0e4e6abf9125\", \"dbus_unittests\": \"b0a627d7b56541e8fad87e71c423df0738ef338d\", \"device_unittests\": \"eafe167cb395d17b3925d50143d16c235fd2b731\", \"display_unittests\": \"9c3f94d96494fc341a9c53c01ac2610b9f783ca0\", \"events_unittests\": \"bc0577a1c955540b9abcc527f16df7c5bec28551\", \"extensions_browsertests\": \"a322be1b880a332a716961728d63f0973c6f7e2f\", \"extensions_unittests\": \"2e0ebdf00fd2d6aef17197a1a0d8f17db53473eb\", \"filesystem_service_unittests\": \"c97c5ac1b0258c6d6c5170d04b281a68bd825642\", \"flatbuffers_unittests\": \"050844e0d26621703adfe93dfd2f97054fb85d6c\", \"gcm_unit_tests\": \"8cfe77df1f7d6c26c861d7a9d05a37944b430ab1\", \"gfx_unittests\": \"9f5d6fddb7b91cc90850120b3893c9e44cc02da0\", \"gin_unittests\": \"cdca516895d7ebde09af19f2f696c4482f3cd808\", \"google_apis_unittests\": \"d1f93b57b0c59571ae787808579af523c086ad4c\", \"gpu_unittests\": \"090b088aae14c2539ae61885ff5d084e463c6543\", \"grit_python_unittests\": \"300ea8455288eab00cd459e887bd99a4f6468e8a\", \"gwp_asan_unittests\": \"5c432fcfac9788a63b1dd2d19c0fd4451d7b6fe2\", \"headless_browsertests\": \"8b734b579d1ed73ceef038d6d09a09f65d2aa649\", \"headless_unittests\": \"762a10635c347374ab07b36809abf7adb1c8aebf\", \"interactive_ui_tests\": \"45c5666b987d66c6f450771de6e128431e2dca2d\", \"ipc_tests\": \"aa8a930e8fa7a0c9b82eb9dab6b84fa80139e2c6\", \"jingle_unittests\": \"152855c1eaf5c89f6864d99fd320c58721dfd07a\", \"latency_unittests\": \"40f83bb8fcaa5555e39b6c755bb82621a5cd3513\", \"libjingle_xmpp_unittests\": \"2feef4ca46d65ef8e1184b6c10e7c43f11ea807a\", \"media_blink_unittests\": \"1c343211eb217cfa001c374d82fe4258f350f20b\", \"media_service_unittests\": \"ca5aacbfc05d33ee895bc54355266529284c265b\", \"media_unittests\": \"5de8d9ab0e590059852ed0bcecfa22bafd86de47\", \"message_center_unittests\": \"60753884e9007224b8ad4a14f7803bab0fdbdd00\", \"metrics_python_tests\": \"965f65680feea2ba067fd67b6f66c9b8cfae6a57\", \"midi_unittests\": \"b1d6655473f840b1adcf8c2fbde387e5bf30798a\", \"mojo_core_unittests\": \"be1686b2018df1d7e0722ea18bec213c1c3d20b1\", \"mojo_unittests\": \"4cd34b015a9d1eb573112c02edff224842772b02\", \"nacl_helper_nonsfi_unittests\": \"6d04c4d98ea4cc7904317e7a25b32eedd7f97c92\", \"nacl_loader_unittests\": \"924fc4af12aa66917cf583fa8ae2233d258959df\", \"native_theme_unittests\": \"0ce16fdca30ca55dbeaa60af77cbe75f870f751a\", \"net_unittests\": \"3ba9e384e6a90c69f2ff795ada60ec6a5502e607\", \"pdf_unittests\": \"54ec3efd22710faa43e3d8633e3d4bd9e36575c5\", \"perfetto_unittests\": \"95e57c7773c7dc06942c57d7bfeb128ba3e230bb\", \"ppapi_unittests\": \"c38705f1b9a77f339d409d489d62397bb6199d43\", \"printing_unittests\": \"9dc9fb64bf0ba8bbc29b4dd6164a48182f69ee6b\", \"remoting_unittests\": \"893b6a1839a5d4649c8c192413b9f5d6f157b108\", \"sandbox_linux_unittests\": \"85617a8e43572982cfdb6638830fd5b3dbdad22a\", \"service_manager_unittests\": \"59fd1204c6fca87ef14ee73e291b0fc2dfe4aaa2\", \"services_unittests\": \"c4d3a96605e228d5deef5b30769f16c1788fd07e\", \"shell_dialogs_unittests\": \"45f9d7065a1917f9c6bb0fb931aebd353e41406b\", \"skia_unittests\": \"c52ed342abe3f050c677113e2de0f6886d830e2c\", \"snapshot_unittests\": \"431baf3199971e242777597b28491fae67caac32\", \"sql_unittests\": \"2314c1f9446bb97382d9ec1caba74859b6272464\", \"storage_unittests\": \"aba993219dd5e48ebf9fd0b5bbe984909a47da25\", \"sync_integration_tests\": \"ee4eb1b3e57ef7cae57c9a890589f7d02ea2664c\", \"telemetry_gpu_unittests\": \"b093f2ebdf6b17fc812b8b8f586eae3f401b5f0c\", \"telemetry_perf_unittests\": \"6e7863ebc7a9dddd27dc1bae78d9e927046cef30\", \"telemetry_unittests\": \"a2be0e420de5b5a0a1eee501e034d8764084ae04\", \"traffic_annotation_auditor_unittests\": \"04cae16dc24ab960334278c78a50690b08dc365c\", \"ui_base_unittests\": \"4d65964d0d81e8a65da8a88c0247c50d0ebc3489\", \"ui_touch_selection_unittests\": \"660d657458ecdd1d9e570c00672958c73be24da0\", \"unit_tests\": \"03f803533949462e89270d8e4fa10a547335b028\", \"url_unittests\": \"1db7b7528760076336bcac63ffd81b83ce48ad9b\", \"views_perftests\": \"f2f13d33bba1dae268fdda9fb7c6734f90dea0d4\", \"views_unittests\": \"1657ad09340358dee967a8f3d8b731f8cada438c\", \"viz_unittests\": \"759186e1db6315136a8577f4714fa2bf4df121cf\", \"vr_common_unittests\": \"fe3abced26394f6f98c513b675bc5ff3874e2e1b\", \"vr_pixeltests\": \"9760dec53f8798752058db0bb88275262c2181db\", \"webdriver_wpt_tests\": \"37875b8c59d081470c086c4299c857b77f5f8099\", \"weblayer_browsertests\": \"b3232862473d082245040c3cbf251ac96e7900a2\", \"wm_unittests\": \"5f859541f02a609a4c487262e54ed05365449ea0\", \"wtf_unittests\": \"291855339b346b4ee7194c6e3850266d317eb9fc\", \"xr_browser_tests\": \"1a6b5e7b71269ccf391a2c4beb6915e94f368c6f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_49b2c59a41dab71fb2bba5f6f8a4efb56888b0948fbb2a1ad8fa728b25e0dc17_v2\", \"builder_4ed015b5fc9c2d76058712113f755b0be3aac2b79f47625be53c8010fb48cc39_v2\", \"builder_7d75f6999c07e42a7f7fde2313383264fbd8ed871e2008bde33b278973c1f4c8_v2\", \"builder_a1b9c6892397d61c3624774723419f5ae48fcf776e7d2304bd4682bf5cd4c049_v2\", \"builder_f2d41554cc5e5ccdd409a1e3331deca1f73b654eb50c3540ea4abf5478e808d4_v2\", \"builder_ffe623326ac74578ce917ff55ef6cefa75094e5ad3ad5fe3409a729d79be647a_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-east1-d-8-72-19hg\"], \"image\": [\"chrome-xenial-20030100-2bddf837f90\"], \"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\": [\"4851-268d16b\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1583878267497063",
"status": "COMPLETED",
"status_changed_ts": "1583881554116633",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/169583",
"builder:Linux Builder (dbg)",
"buildset:commit/git/dd589029655574e7c6b4bc82b8dc597ae815e4e0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dd589029655574e7c6b4bc82b8dc597ae815e4e0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9051607313454060368",
"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/8886177251130358080/+/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:4adf4df2bb06ac10",
"user_agent:luci-scheduler"
],
"updated_ts": "1583881554134279",
"url": "https://ci.chromium.org/b/8886177251130358080",
"utcnow_ts": "1583881554787026"
}
}
[23:05:54.796] Invocation finished in 59m9.624887729s with status SUCCEEDED