[16:09:47.523] New invocation is queued and will start shortly
[16:09:51.296] Starting the invocation (attempt 1)
[16:09:51.423] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:09:51.423] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:09:51.423] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9055797231957423360",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"345bfdd8140880de7f60318af21c813da3ffa1a0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9055797231957423360",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/345bfdd8140880de7f60318af21c813da3ffa1a0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/345bfdd8140880de7f60318af21c813da3ffa1a0",
"gitiles_ref:refs/heads/master"
]
}
[16:09:52.680] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1579882191609078",
"id": "8890367167010165920",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"345bfdd8140880de7f60318af21c813da3ffa1a0\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1579882192318163",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/167787",
"builder:Linux Builder (dbg)",
"buildset:commit/git/345bfdd8140880de7f60318af21c813da3ffa1a0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/345bfdd8140880de7f60318af21c813da3ffa1a0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9055797231957423360",
"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/8890367167010165920/+/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": "1579882192318261",
"url": "https://ci.chromium.org/b/8890367167010165920",
"utcnow_ts": "1579882192669272"
}
}
[16:09:52.680] Task URL: https://ci.chromium.org/b/8890367167010165920
[16:09:52.680] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:2:0) after 1m0s
[16:10:52.876] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:2:0)
[16:10:52.876] Timer tick, asking Buildbucket for the build status
[16:10:53.064] Build 8890367167010165920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:10:53.064] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:3:0) after 1m0s
[16:11:53.260] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:3:0)
[16:11:53.260] Timer tick, asking Buildbucket for the build status
[16:11:53.571] Build 8890367167010165920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:11:53.571] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:4:0) after 1m0s
[16:12:53.755] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:4:0)
[16:12:53.755] Timer tick, asking Buildbucket for the build status
[16:12:54.234] Build 8890367167010165920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:12:54.234] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:5:0) after 1m0s
[16:13:54.252] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:5:0)
[16:13:54.252] Timer tick, asking Buildbucket for the build status
[16:13:54.435] Build 8890367167010165920: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:13:54.435] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:6:0) after 1m0s
[16:14:10.022] Received PubSub notification, asking Buildbucket for the build status
[16:14:10.160] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:54.493] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:6:0)
[16:14:54.493] Timer tick, asking Buildbucket for the build status
[16:14:54.833] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:54.833] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:8:0) after 1m0s
[16:15:54.901] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:8:0)
[16:15:54.901] Timer tick, asking Buildbucket for the build status
[16:15:55.393] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:55.393] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:9:0) after 1m0s
[16:16:55.553] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:9:0)
[16:16:55.553] Timer tick, asking Buildbucket for the build status
[16:16:55.771] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:55.771] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:10:0) after 1m0s
[16:17:55.837] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:10:0)
[16:17:55.837] Timer tick, asking Buildbucket for the build status
[16:17:56.380] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:56.380] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:11:0) after 1m0s
[16:18:56.462] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:11:0)
[16:18:56.462] Timer tick, asking Buildbucket for the build status
[16:18:56.699] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:56.699] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:12:0) after 1m0s
[16:19:56.750] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:12:0)
[16:19:56.750] Timer tick, asking Buildbucket for the build status
[16:19:56.902] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:56.902] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:13:0) after 1m0s
[16:20:57.116] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:13:0)
[16:20:57.116] Timer tick, asking Buildbucket for the build status
[16:20:57.302] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:57.302] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:14:0) after 1m0s
[16:21:57.378] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:14:0)
[16:21:57.378] Timer tick, asking Buildbucket for the build status
[16:21:58.947] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:58.947] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:15:0) after 1m0s
[16:22:59.023] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:15:0)
[16:22:59.023] Timer tick, asking Buildbucket for the build status
[16:22:59.768] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:59.768] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:16:0) after 1m0s
[16:23:59.837] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:16:0)
[16:23:59.837] Timer tick, asking Buildbucket for the build status
[16:24:00.136] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:00.136] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:17:0) after 1m0s
[16:25:00.313] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:17:0)
[16:25:00.313] Timer tick, asking Buildbucket for the build status
[16:25:00.660] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:00.661] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:18:0) after 1m0s
[16:26:00.854] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:18:0)
[16:26:00.854] Timer tick, asking Buildbucket for the build status
[16:26:01.074] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:01.074] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:19:0) after 1m0s
[16:27:01.325] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:19:0)
[16:27:01.325] Timer tick, asking Buildbucket for the build status
[16:27:01.552] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:01.552] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:20:0) after 1m0s
[16:28:01.570] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:20:0)
[16:28:01.570] Timer tick, asking Buildbucket for the build status
[16:28:01.941] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:01.941] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:21:0) after 1m0s
[16:29:02.064] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:21:0)
[16:29:02.064] Timer tick, asking Buildbucket for the build status
[16:29:02.699] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:02.699] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:22:0) after 1m0s
[16:30:02.809] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:22:0)
[16:30:02.809] Timer tick, asking Buildbucket for the build status
[16:30:03.271] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:03.271] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:23:0) after 1m0s
[16:31:03.296] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:23:0)
[16:31:03.296] Timer tick, asking Buildbucket for the build status
[16:31:03.490] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:03.490] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:24:0) after 1m0s
[16:32:03.565] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:24:0)
[16:32:03.565] Timer tick, asking Buildbucket for the build status
[16:32:04.357] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:04.357] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:25:0) after 1m0s
[16:33:04.467] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:25:0)
[16:33:04.467] Timer tick, asking Buildbucket for the build status
[16:33:04.923] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:04.923] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:26:0) after 1m0s
[16:34:04.965] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:26:0)
[16:34:04.965] Timer tick, asking Buildbucket for the build status
[16:34:05.178] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:05.178] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:27:0) after 1m0s
[16:35:05.215] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:27:0)
[16:35:05.215] Timer tick, asking Buildbucket for the build status
[16:35:05.737] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:05.737] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:28:0) after 1m0s
[16:36:05.792] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:28:0)
[16:36:05.792] Timer tick, asking Buildbucket for the build status
[16:36:06.040] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:06.040] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:29:0) after 1m0s
[16:37:06.123] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:29:0)
[16:37:06.123] Timer tick, asking Buildbucket for the build status
[16:37:06.628] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:06.629] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:30:0) after 1m0s
[16:38:06.744] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:30:0)
[16:38:06.744] Timer tick, asking Buildbucket for the build status
[16:38:07.396] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:07.396] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:31:0) after 1m0s
[16:39:07.592] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:31:0)
[16:39:07.592] Timer tick, asking Buildbucket for the build status
[16:39:07.759] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:07.759] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:32:0) after 1m0s
[16:40:08.079] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:32:0)
[16:40:08.079] Timer tick, asking Buildbucket for the build status
[16:40:08.420] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:08.420] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:33:0) after 1m0s
[16:41:08.533] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:33:0)
[16:41:08.533] Timer tick, asking Buildbucket for the build status
[16:41:09.125] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:09.125] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:34:0) after 1m0s
[16:42:09.244] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:34:0)
[16:42:09.244] Timer tick, asking Buildbucket for the build status
[16:42:09.793] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:09.793] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:35:0) after 1m0s
[16:43:09.856] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:35:0)
[16:43:09.856] Timer tick, asking Buildbucket for the build status
[16:43:09.991] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:09.991] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:36:0) after 1m0s
[16:44:10.130] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:36:0)
[16:44:10.130] Timer tick, asking Buildbucket for the build status
[16:44:10.384] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:10.385] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:37:0) after 1m0s
[16:45:10.469] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:37:0)
[16:45:10.469] Timer tick, asking Buildbucket for the build status
[16:45:11.070] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:11.070] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:38:0) after 1m0s
[16:46:11.244] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:38:0)
[16:46:11.244] Timer tick, asking Buildbucket for the build status
[16:46:11.445] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:11.445] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:39:0) after 1m0s
[16:47:11.453] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:39:0)
[16:47:11.453] Timer tick, asking Buildbucket for the build status
[16:47:11.919] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:11.919] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:40:0) after 1m0s
[16:48:12.037] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:40:0)
[16:48:12.037] Timer tick, asking Buildbucket for the build status
[16:48:12.193] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:12.193] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:41:0) after 1m0s
[16:49:12.260] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:41:0)
[16:49:12.260] Timer tick, asking Buildbucket for the build status
[16:49:12.385] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:12.385] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:42:0) after 1m0s
[16:50:12.502] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:42:0)
[16:50:12.502] Timer tick, asking Buildbucket for the build status
[16:50:13.297] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:13.297] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:43:0) after 1m0s
[16:51:13.331] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:43:0)
[16:51:13.331] Timer tick, asking Buildbucket for the build status
[16:51:13.563] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:13.563] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:44:0) after 1m0s
[16:52:13.631] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:44:0)
[16:52:13.631] Timer tick, asking Buildbucket for the build status
[16:52:14.378] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:14.378] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:45:0) after 1m0s
[16:53:14.469] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:45:0)
[16:53:14.469] Timer tick, asking Buildbucket for the build status
[16:53:14.632] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:14.632] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:46:0) after 1m0s
[16:54:14.633] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:46:0)
[16:54:14.633] Timer tick, asking Buildbucket for the build status
[16:54:14.929] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:14.929] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:47:0) after 1m0s
[16:55:14.903] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:47:0)
[16:55:14.903] Timer tick, asking Buildbucket for the build status
[16:55:15.679] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:15.679] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:48:0) after 1m0s
[16:56:15.782] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:48:0)
[16:56:15.782] Timer tick, asking Buildbucket for the build status
[16:56:15.935] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:15.935] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:49:0) after 1m0s
[16:57:15.954] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:49:0)
[16:57:15.954] Timer tick, asking Buildbucket for the build status
[16:57:17.376] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:17.376] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:50:0) after 1m0s
[16:58:17.397] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:50:0)
[16:58:17.397] Timer tick, asking Buildbucket for the build status
[16:58:17.968] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:17.968] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:51:0) after 1m0s
[16:59:17.984] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:51:0)
[16:59:17.984] Timer tick, asking Buildbucket for the build status
[16:59:18.676] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:18.676] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:52:0) after 1m0s
[17:00:18.817] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:52:0)
[17:00:18.817] Timer tick, asking Buildbucket for the build status
[17:00:19.000] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:19.000] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:53:0) after 1m0s
[17:01:19.155] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:53:0)
[17:01:19.155] Timer tick, asking Buildbucket for the build status
[17:01:19.397] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:19.397] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:54:0) after 1m0s
[17:02:19.415] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:54:0)
[17:02:19.415] Timer tick, asking Buildbucket for the build status
[17:02:20.041] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:20.041] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:55:0) after 1m0s
[17:03:20.142] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:55:0)
[17:03:20.142] Timer tick, asking Buildbucket for the build status
[17:03:20.558] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:20.558] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:56:0) after 1m0s
[17:04:20.583] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:56:0)
[17:04:20.583] Timer tick, asking Buildbucket for the build status
[17:04:20.791] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:20.791] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:57:0) after 1m0s
[17:05:20.844] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:57:0)
[17:05:20.844] Timer tick, asking Buildbucket for the build status
[17:05:22.167] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:22.167] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:58:0) after 1m0s
[17:06:22.358] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:58:0)
[17:06:22.358] Timer tick, asking Buildbucket for the build status
[17:06:22.544] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:22.544] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:59:0) after 1m0s
[17:07:22.838] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:59:0)
[17:07:22.838] Timer tick, asking Buildbucket for the build status
[17:07:23.117] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:23.117] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:60:0) after 1m0s
[17:08:23.158] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:60:0)
[17:08:23.158] Timer tick, asking Buildbucket for the build status
[17:08:23.468] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:23.468] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:61:0) after 1m0s
[17:09:23.539] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:61:0)
[17:09:23.539] Timer tick, asking Buildbucket for the build status
[17:09:23.702] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:23.702] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:62:0) after 1m0s
[17:10:23.749] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:62:0)
[17:10:23.749] Timer tick, asking Buildbucket for the build status
[17:10:23.983] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:23.983] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:63:0) after 1m0s
[17:11:24.036] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:63:0)
[17:11:24.036] Timer tick, asking Buildbucket for the build status
[17:11:24.371] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:24.371] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:64:0) after 1m0s
[17:12:24.392] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:64:0)
[17:12:24.392] Timer tick, asking Buildbucket for the build status
[17:12:24.670] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:24.670] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:65:0) after 1m0s
[17:13:24.680] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:65:0)
[17:13:24.680] Timer tick, asking Buildbucket for the build status
[17:13:25.323] Build 8890367167010165920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:25.323] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9055797231957423360:66:0) after 1m0s
[17:14:11.700] Received PubSub notification, asking Buildbucket for the build status
[17:14:12.325] Build 8890367167010165920: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:14:12.325] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1579886050849695",
"created_by": "project:chromium",
"created_ts": "1579882191609078",
"id": "8890367167010165920",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"345bfdd8140880de7f60318af21c813da3ffa1a0\"}}",
"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-129-k9bo\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 167787, \"got_angle_revision\": \"4e98dfeaf092a573e4d766a8f2fbceadec0ea62f\", \"got_dawn_revision\": \"d87e67684558fd46e15c382b78558fb13a091920\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"345bfdd8140880de7f60318af21c813da3ffa1a0\", \"got_revision_cp\": \"refs/heads/master@{#734951}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"5ef91b82cbedb4e262af2cb4fddaee7006de8818\", \"got_v8_revision\": \"781941ba6e92b695f21a1455f4fb840417202a1b\", \"got_v8_revision_cp\": \"refs/heads/8.1.274@{#1}\", \"got_webrtc_revision\": \"159c414ff8f6ae9b90abca6ab5cd9fce7a2a1801\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30369}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"345bfdd8140880de7f60318af21c813da3ffa1a0\", \"swarm_hashes_refs/heads/master(at){#734951}_without_patch\": {\"accessibility_unittests\": \"878097540124996dacbd098a22d0d66f2ec27fa7\", \"angle_unittests\": \"d8992733e09b089a9e8be695a576ef03d167cb4f\", \"app_shell_unittests\": \"6d8ff13d56023b242b2fff69d843c97aab9a7116\", \"aura_unittests\": \"efae53187fead7684ae869bb1677d7af8d3c5159\", \"base_unittests\": \"131c4f34c0d4a6a823849ae2fba0da50a3db065c\", \"base_util_unittests\": \"20ec693eb396e67eb399f64c836eba85c4579f7f\", \"blink_common_unittests\": \"8e816332696ac3127181f8ed80eef466141d9473\", \"blink_fuzzer_unittests\": \"faa90d79e0882e2a0646512302ef215c9b4fa3ed\", \"blink_heap_unittests\": \"bd6a06622a668eb79d9e127c13f5270056850477\", \"blink_platform_unittests\": \"d427f16ee605982daa0d40f981d069904b541c8a\", \"blink_python_tests\": \"9defc9d244cee21b0c2d443359cc9b6621bd70ea\", \"blink_unittests\": \"9276be97fe9e0b9233a4146ce26d69293f0247d0\", \"blink_web_tests\": \"986fb30554858a1edf087ea246866f83b9047604\", \"boringssl_crypto_tests\": \"104106235ea7c379202077e89fcc57b923e0dd6e\", \"boringssl_ssl_tests\": \"3cd56afc162753c7ae12429b6a43f45dfd6554d3\", \"browser_tests\": \"e07ae1f15f9f5de219d800fcbdcac3b8919f9d49\", \"cacheinvalidation_unittests\": \"7c73b0780c1f251116ff08164e6fe1ae6bf439b1\", \"capture_unittests\": \"11730ecc9907db398bcc965e5905dd0b433e7954\", \"cast_unittests\": \"7e707eaaa36a14952748c0604a8ed767946fff4b\", \"cc_unittests\": \"ad2b279377f5f317721098ef3ffcb67fb6cd4858\", \"chrome_app_unittests\": \"85113aad8782e14c0cc336a28b9adca345cd9e34\", \"chromedriver_unittests\": \"97b8b23e54a098342cdb90994aae357e21deee16\", \"components_browsertests\": \"b45ceef3bde3a04bc07299626a7a3a86d92b3d81\", \"components_unittests\": \"c5b23a169bebc6002c98717fc19957ec365a00df\", \"compositor_unittests\": \"f9e0b8cfb200c95d10ec56c85e021e567a09a0e6\", \"content_browsertests\": \"8504af73a4134a18e7b2e9f4e4d9c928fec88374\", \"content_shell_crash_test\": \"15aec173e0acff194ff1ecce0f25b086454c585d\", \"content_unittests\": \"9f1a7dc3a6ccd7c15aca258ebba26943f2c7e1e6\", \"crashpad_tests\": \"9a99cbd1f85ffe841b2af29c6efab659cd9701c7\", \"cronet_tests\": \"a41e77e8a8a2303eda6d391b5f2dfac08d5c2b38\", \"cronet_unittests\": \"4ffb78c7cf83d5d12b9475f40d7d8b0d04756743\", \"crypto_unittests\": \"8c157897fff23200b4cb2afb4ba3280aa10a1dac\", \"dbus_unittests\": \"7365d61f7035f383f98a580ae95110ff5290428d\", \"device_unittests\": \"d28d0e9645d439285b7b81881de4560b65c64eb6\", \"display_unittests\": \"e0b4ff48aeabbeefb03f3752e5dd9c1bcc062355\", \"events_unittests\": \"a9d9c0de0622f8aab1358da6a10d701696b3bf0d\", \"extensions_browsertests\": \"f81c5fa71c557713c958d252513be0551bf5d7b4\", \"extensions_unittests\": \"4734089656aad3fa44bb2ae1d052ada782ba7d54\", \"filesystem_service_unittests\": \"9b8d1a9a887a365f5b91089777a053b469aca3ca\", \"flatbuffers_unittests\": \"92c174f535dbe8e15f58589d59dc88efc29a10d4\", \"gcm_unit_tests\": \"6ffeb4ea82b6b1a6ff2f83171557ff6e608656cd\", \"gfx_unittests\": \"650ea5082d97343c75e5b57d23bf91502d55324f\", \"gin_unittests\": \"26b763f5f9c47b862fb3f8d854c21e7a8f0802a7\", \"google_apis_unittests\": \"eb7a94b002f6ca877750e1986e60a1124e9eb164\", \"gpu_unittests\": \"640e8d971a3d46f462cee816e246d6e5d8c086b8\", \"grit_python_unittests\": \"d715786376a2cd01028269e386a2464ac7cd5a43\", \"gwp_asan_unittests\": \"9bd65cc559fe28c7f62b99480254bc43501eb9e3\", \"headless_browsertests\": \"f278ca217299942d2c709677be7289bb031e2f90\", \"headless_unittests\": \"c5f7d852e5ca5416aed1c7deaaa1833c967c47c6\", \"interactive_ui_tests\": \"478047d41eb04f5e87e1f98cd8a7574ee7d87acd\", \"ipc_tests\": \"e8cb8531908fb94385e96209ac72021c359a2169\", \"jingle_unittests\": \"fb1de40b134a8b11354e2f33a8678493b4b021af\", \"latency_unittests\": \"6a83a77ba44975a2f3bcad8ec4713455b901de31\", \"libjingle_xmpp_unittests\": \"193dae6e80659328718c400de2b9ffb2ea769e0b\", \"media_blink_unittests\": \"c97460d37c732e05c4c782aa333633a8c69d507e\", \"media_service_unittests\": \"fe62626847923c0213fa89d047b2689bb7df677f\", \"media_unittests\": \"a4dc5e30d3725856d1e8979543f86a1f50e3b4f2\", \"message_center_unittests\": \"73fa11755fb14ebbca7c19fd18d3d741dc5fe2f8\", \"metrics_python_tests\": \"9b7152c25648afe5010ff8565c5d0b393588b515\", \"midi_unittests\": \"901d77dda6259eba5aef7452f269095b8b917e8c\", \"mojo_core_unittests\": \"2bb8fb342c21ca4c8fe271657edce54db5ce2489\", \"mojo_unittests\": \"3762a7b6a58e701e75e6c1bf8fae485bfa27b468\", \"nacl_helper_nonsfi_unittests\": \"894e3b520aeeb7e05d53750d7f1e0fd42621a048\", \"nacl_loader_unittests\": \"08840f7e846aeab98dd549a55a7ae84675e42a6d\", \"native_theme_unittests\": \"73c692aee78626def4e4aa12c2d35b24fb4a3290\", \"net_unittests\": \"8abbce8a490d703a0d952165b6af5fab1c5aaa23\", \"pdf_unittests\": \"aa60dba4e60a1ad79fc569edc6a69daf2db3339f\", \"perfetto_unittests\": \"421d2a56ebce4de9fc2f92f3d6d3b0ff1d57d118\", \"ppapi_unittests\": \"9de42a04b0551ea1f8d931a8053716898681170f\", \"printing_unittests\": \"f680cfb8eeb41a95ad93d165951948e781d61e26\", \"remoting_unittests\": \"6ef14343b2380cd26b9554edcc3cbb352d4de08a\", \"sandbox_linux_unittests\": \"f018fda7ade64c944f18ad3958d4786e85b8bc30\", \"service_manager_unittests\": \"0960f44f73798158ae87b9085a22638fc5e6550c\", \"services_unittests\": \"ef0406c2c10e7061095fce6297d22218c683bef8\", \"shell_dialogs_unittests\": \"6d62a475d751ed82eb71bfbef8a744d038b6ee06\", \"skia_unittests\": \"b4e262596c0aee1e48d784d9ce83dd708397ff7d\", \"snapshot_unittests\": \"d1e4372fe14a38e7632afabb1f86d11bec591322\", \"sql_unittests\": \"b1aef898e6ae0b08e8c440131b727dedbec8f851\", \"storage_unittests\": \"16cceee588edfe0a44eecfb745b23f15a74b8269\", \"sync_integration_tests\": \"1f975c8538572ddb392c6c4833f6b7e4a3b95044\", \"telemetry_gpu_unittests\": \"13a5e069449d9a961dc10edcee0ec8fc3238914a\", \"telemetry_perf_unittests\": \"37e18ca10f714cd7153425cec3bf03c5134b5b83\", \"telemetry_unittests\": \"c3d29eb0ddb16fcd382ef8bccaf6cb89a74b9785\", \"traffic_annotation_auditor_unittests\": \"5e69ba6e81f225e8b4e2c9f738e3622bb09d5ce7\", \"ui_base_unittests\": \"ca3720dac2dfffef2ae15e60d898d746efc4a0cf\", \"ui_touch_selection_unittests\": \"bacc372158ed89d88b0d9ee7dcee38be81a7d7c2\", \"unit_tests\": \"6f9f8cf2c5e4189b3b5e2e501b84eca213c432ff\", \"url_unittests\": \"616c5369f512c971ecaec2b475f8981623fd8a5d\", \"views_perftests\": \"750b8da98f9068a3ffbb455b4cbba67ae1906410\", \"views_unittests\": \"48d632459beac98f7ef9f0c590e450254d9d1e82\", \"viz_unittests\": \"93c35f6af40de5926879bab19195889990720a7f\", \"vr_common_unittests\": \"ac2dc4560bcb5b99c26a3e84a3a89621902cbdde\", \"vr_pixeltests\": \"509ba7d481bcbf30b67df381e86f38581c3000cb\", \"webdriver_wpt_tests\": \"f60cb994054999098fca30044aa58e2f8113e174\", \"weblayer_browsertests\": \"34bba9accc7692b239d18321e85a994ac23bc4dc\", \"wm_unittests\": \"fcd37cfa120e73f02306578125f6a28dc20bde6b\", \"wtf_unittests\": \"491602b82c54708aaa29055e9467a01bd98884f6\", \"xr_browser_tests\": \"47e874ed5c86e4077fa99db81b7a1b860b83d686\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4f073cad989671df25347297d55a43b2baed7923c7c8febe8eed874750cd540a_v2\", \"builder_78902ab2d70d1593a97658cf8910c0e117cf7950e257117b8c551c6fe1cfb403_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-129-k9bo\"], \"image\": [\"chrome-xenial-20011200-47b90f1b63b\"], \"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\": [\"4792-2014df3\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1579882449148656",
"status": "COMPLETED",
"status_changed_ts": "1579886051241631",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/167787",
"builder:Linux Builder (dbg)",
"buildset:commit/git/345bfdd8140880de7f60318af21c813da3ffa1a0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/345bfdd8140880de7f60318af21c813da3ffa1a0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9055797231957423360",
"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/8890367167010165920/+/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:49f122a7a5ba1f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1579886051245556",
"url": "https://ci.chromium.org/b/8890367167010165920",
"utcnow_ts": "1579886052310560"
}
}
[17:14:12.325] Invocation finished in 1h4m24.81827769s with status SUCCEEDED