[22:26:16.283] New invocation is queued and will start shortly
[22:26:17.437] Starting the invocation (attempt 1)
[22:26:17.473] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:26:17.473] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:26:17.473] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9054505188397077120",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"dfc72a0a7ba1f77921ba74558d792bd86da2e883\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9054505188397077120",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/dfc72a0a7ba1f77921ba74558d792bd86da2e883",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dfc72a0a7ba1f77921ba74558d792bd86da2e883",
"gitiles_ref:refs/heads/master"
]
}
[22:26:18.460] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1581114377688030",
"id": "8889075126259526336",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dfc72a0a7ba1f77921ba74558d792bd86da2e883\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1581114378275923",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168354",
"builder:Linux Builder (dbg)",
"buildset:commit/git/dfc72a0a7ba1f77921ba74558d792bd86da2e883",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dfc72a0a7ba1f77921ba74558d792bd86da2e883",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054505188397077120",
"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/8889075126259526336/+/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": "1581114378276156",
"url": "https://ci.chromium.org/b/8889075126259526336",
"utcnow_ts": "1581114378448661"
}
}
[22:26:18.460] Task URL: https://ci.chromium.org/b/8889075126259526336
[22:26:18.460] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:2:0) after 1m0s
[22:27:18.597] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:2:0)
[22:27:18.597] Timer tick, asking Buildbucket for the build status
[22:27:18.786] Build 8889075126259526336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:27:18.786] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:3:0) after 1m0s
[22:28:19.219] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:3:0)
[22:28:19.219] Timer tick, asking Buildbucket for the build status
[22:28:19.918] Build 8889075126259526336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:28:19.918] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:4:0) after 1m0s
[22:29:20.008] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:4:0)
[22:29:20.008] Timer tick, asking Buildbucket for the build status
[22:29:20.165] Build 8889075126259526336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:29:20.165] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:5:0) after 1m0s
[22:30:20.248] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:5:0)
[22:30:20.248] Timer tick, asking Buildbucket for the build status
[22:30:20.724] Build 8889075126259526336: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:30:20.724] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:6:0) after 1m0s
[22:31:17.297] Received PubSub notification, asking Buildbucket for the build status
[22:31:19.320] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:20.943] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:6:0)
[22:31:20.943] Timer tick, asking Buildbucket for the build status
[22:31:21.175] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:21.175] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:8:0) after 1m0s
[22:32:21.337] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:8:0)
[22:32:21.337] Timer tick, asking Buildbucket for the build status
[22:32:21.809] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:21.809] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:9:0) after 1m0s
[22:33:21.933] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:9:0)
[22:33:21.933] Timer tick, asking Buildbucket for the build status
[22:33:22.119] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:22.119] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:10:0) after 1m0s
[22:34:22.284] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:10:0)
[22:34:22.284] Timer tick, asking Buildbucket for the build status
[22:34:22.433] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:22.433] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:11:0) after 1m0s
[22:35:22.727] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:11:0)
[22:35:22.727] Timer tick, asking Buildbucket for the build status
[22:35:22.866] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:22.866] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:12:0) after 1m0s
[22:36:22.966] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:12:0)
[22:36:22.966] Timer tick, asking Buildbucket for the build status
[22:36:23.121] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:23.121] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:13:0) after 1m0s
[22:37:23.276] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:13:0)
[22:37:23.276] Timer tick, asking Buildbucket for the build status
[22:37:23.401] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:23.401] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:14:0) after 1m0s
[22:38:23.457] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:14:0)
[22:38:23.457] Timer tick, asking Buildbucket for the build status
[22:38:23.620] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:23.620] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:15:0) after 1m0s
[22:39:24.061] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:15:0)
[22:39:24.061] Timer tick, asking Buildbucket for the build status
[22:39:25.019] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:25.019] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:16:0) after 1m0s
[22:40:25.058] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:16:0)
[22:40:25.058] Timer tick, asking Buildbucket for the build status
[22:40:26.593] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:26.593] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:17:0) after 1m0s
[22:41:26.758] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:17:0)
[22:41:26.758] Timer tick, asking Buildbucket for the build status
[22:41:27.504] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:27.504] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:18:0) after 1m0s
[22:42:27.831] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:18:0)
[22:42:27.831] Timer tick, asking Buildbucket for the build status
[22:42:28.037] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:28.037] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:19:0) after 1m0s
[22:43:28.320] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:19:0)
[22:43:28.320] Timer tick, asking Buildbucket for the build status
[22:43:28.791] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:28.791] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:20:0) after 1m0s
[22:44:29.062] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:20:0)
[22:44:29.062] Timer tick, asking Buildbucket for the build status
[22:44:29.237] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:29.237] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:21:0) after 1m0s
[22:45:29.294] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:21:0)
[22:45:29.294] Timer tick, asking Buildbucket for the build status
[22:45:29.893] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:29.893] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:22:0) after 1m0s
[22:46:30.001] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:22:0)
[22:46:30.001] Timer tick, asking Buildbucket for the build status
[22:46:30.146] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:30.146] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:23:0) after 1m0s
[22:47:30.259] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:23:0)
[22:47:30.259] Timer tick, asking Buildbucket for the build status
[22:47:30.432] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:30.432] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:24:0) after 1m0s
[22:48:30.555] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:24:0)
[22:48:30.555] Timer tick, asking Buildbucket for the build status
[22:48:30.716] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:30.716] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:25:0) after 1m0s
[22:49:30.910] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:25:0)
[22:49:30.910] Timer tick, asking Buildbucket for the build status
[22:49:31.068] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:31.068] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:26:0) after 1m0s
[22:50:31.222] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:26:0)
[22:50:31.222] Timer tick, asking Buildbucket for the build status
[22:50:31.605] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:31.605] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:27:0) after 1m0s
[22:51:31.935] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:27:0)
[22:51:31.935] Timer tick, asking Buildbucket for the build status
[22:51:32.180] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:32.180] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:28:0) after 1m0s
[22:52:32.360] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:28:0)
[22:52:32.360] Timer tick, asking Buildbucket for the build status
[22:52:32.572] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:32.572] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:29:0) after 1m0s
[22:53:32.646] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:29:0)
[22:53:32.646] Timer tick, asking Buildbucket for the build status
[22:53:32.988] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:32.988] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:30:0) after 1m0s
[22:54:32.989] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:30:0)
[22:54:32.989] Timer tick, asking Buildbucket for the build status
[22:54:33.502] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:33.503] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:31:0) after 1m0s
[22:55:33.514] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:31:0)
[22:55:33.514] Timer tick, asking Buildbucket for the build status
[22:55:33.704] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:33.704] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:32:0) after 1m0s
[22:56:33.720] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:32:0)
[22:56:33.720] Timer tick, asking Buildbucket for the build status
[22:56:35.495] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:35.495] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:33:0) after 1m0s
[22:57:35.658] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:33:0)
[22:57:35.658] Timer tick, asking Buildbucket for the build status
[22:57:36.197] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:36.197] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:34:0) after 1m0s
[22:58:36.354] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:34:0)
[22:58:36.354] Timer tick, asking Buildbucket for the build status
[22:58:37.189] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:37.189] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:35:0) after 1m0s
[22:59:37.304] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:35:0)
[22:59:37.304] Timer tick, asking Buildbucket for the build status
[22:59:37.458] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:37.458] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:36:0) after 1m0s
[23:00:37.664] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:36:0)
[23:00:37.664] Timer tick, asking Buildbucket for the build status
[23:00:37.882] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:37.882] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:37:0) after 1m0s
[23:01:37.952] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:37:0)
[23:01:37.952] Timer tick, asking Buildbucket for the build status
[23:01:38.104] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:38.104] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:38:0) after 1m0s
[23:02:38.200] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:38:0)
[23:02:38.200] Timer tick, asking Buildbucket for the build status
[23:02:38.393] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:38.393] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:39:0) after 1m0s
[23:03:38.432] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:39:0)
[23:03:38.432] Timer tick, asking Buildbucket for the build status
[23:03:38.559] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:38.559] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:40:0) after 1m0s
[23:04:38.649] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:40:0)
[23:04:38.649] Timer tick, asking Buildbucket for the build status
[23:04:38.835] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:38.835] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:41:0) after 1m0s
[23:05:38.915] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:41:0)
[23:05:38.915] Timer tick, asking Buildbucket for the build status
[23:05:39.043] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:39.043] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:42:0) after 1m0s
[23:06:39.095] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:42:0)
[23:06:39.095] Timer tick, asking Buildbucket for the build status
[23:06:39.267] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:39.267] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:43:0) after 1m0s
[23:07:39.349] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:43:0)
[23:07:39.349] Timer tick, asking Buildbucket for the build status
[23:07:39.782] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:39.782] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:44:0) after 1m0s
[23:08:39.914] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:44:0)
[23:08:39.914] Timer tick, asking Buildbucket for the build status
[23:08:40.152] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:40.152] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:45:0) after 1m0s
[23:09:40.241] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:45:0)
[23:09:40.241] Timer tick, asking Buildbucket for the build status
[23:09:40.551] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:40.551] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:46:0) after 1m0s
[23:10:40.593] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:46:0)
[23:10:40.593] Timer tick, asking Buildbucket for the build status
[23:10:40.755] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:40.755] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:47:0) after 1m0s
[23:11:40.895] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:47:0)
[23:11:40.895] Timer tick, asking Buildbucket for the build status
[23:11:41.870] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:41.870] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:48:0) after 1m0s
[23:12:41.994] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:48:0)
[23:12:41.994] Timer tick, asking Buildbucket for the build status
[23:12:42.585] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:42.585] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:49:0) after 1m0s
[23:13:42.643] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:49:0)
[23:13:42.643] Timer tick, asking Buildbucket for the build status
[23:13:43.652] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:43.652] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:50:0) after 1m0s
[23:14:43.679] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:50:0)
[23:14:43.679] Timer tick, asking Buildbucket for the build status
[23:14:43.893] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:43.893] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:51:0) after 1m0s
[23:15:43.943] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:51:0)
[23:15:43.943] Timer tick, asking Buildbucket for the build status
[23:15:44.615] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:44.615] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:52:0) after 1m0s
[23:16:44.711] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:52:0)
[23:16:44.711] Timer tick, asking Buildbucket for the build status
[23:16:45.271] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:45.273] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:53:0) after 1m0s
[23:17:45.313] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:53:0)
[23:17:45.313] Timer tick, asking Buildbucket for the build status
[23:17:45.518] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:45.518] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:54:0) after 1m0s
[23:18:45.644] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:54:0)
[23:18:45.644] Timer tick, asking Buildbucket for the build status
[23:18:46.302] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:46.302] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:55:0) after 1m0s
[23:19:46.381] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:55:0)
[23:19:46.381] Timer tick, asking Buildbucket for the build status
[23:19:46.561] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:46.561] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:56:0) after 1m0s
[23:20:46.691] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:56:0)
[23:20:46.691] Timer tick, asking Buildbucket for the build status
[23:20:46.849] Build 8889075126259526336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:46.849] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054505188397077120:57:0) after 1m0s
[23:21:41.655] Received PubSub notification, asking Buildbucket for the build status
[23:21:41.792] Build 8889075126259526336: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:21:41.792] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1581117701076502",
"created_by": "project:chromium",
"created_ts": "1581114377688030",
"id": "8889075126259526336",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dfc72a0a7ba1f77921ba74558d792bd86da2e883\"}}",
"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-21-ldcp\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168354, \"got_angle_revision\": \"51fd2958f9a27a70107ffbd2aec25c97c6daf1f1\", \"got_dawn_revision\": \"28e054e8cb7d593142d18742df9e337010357647\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"dfc72a0a7ba1f77921ba74558d792bd86da2e883\", \"got_revision_cp\": \"refs/heads/master@{#739574}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"534d12cd423038c36b88e1a14e96e8a66e342900\", \"got_v8_revision\": \"8a8fa36a16213337b712dadb91de092d182ab9e1\", \"got_v8_revision_cp\": \"refs/heads/8.2.37@{#1}\", \"got_webrtc_revision\": \"8e8b36a94a7a7a1fd0f8093979a406afa56e18c1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30484}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dfc72a0a7ba1f77921ba74558d792bd86da2e883\", \"swarm_hashes_refs/heads/master(at){#739574}_without_patch\": {\"accessibility_unittests\": \"d14a50b6d9d2267f1d4d9661a36a2c383531db39\", \"angle_unittests\": \"6873e51e73a84d2bc528c9dee5c41639b2a70b19\", \"app_shell_unittests\": \"5bba68c9b4b52db2d33285e8fbbbe2d1250e47ff\", \"aura_unittests\": \"c36d7de3c7108427b07f533f0f6f87fa3916e7dc\", \"base_unittests\": \"c300fc34c8a5423f3cc2581e227680bfa3f05595\", \"base_util_unittests\": \"09e6c41524f2a514fcc89aa782529cabdb513aca\", \"blink_common_unittests\": \"7ec493d91d111fc8875900da02bee89dba9ca014\", \"blink_fuzzer_unittests\": \"561741e66556be756cf49b7c3fdce256f6eda0e3\", \"blink_heap_unittests\": \"84ca64fd8fd6e3a4e074cf4a190723f3168ae13e\", \"blink_platform_unittests\": \"ebefa2178434bb4bf9b5192298693d594b3cca59\", \"blink_python_tests\": \"34d23b3ae20544b64056025f08efc6349f310df5\", \"blink_unittests\": \"3c9bfd87f3e73f8f575b34e7c2e4ab9a4001400b\", \"blink_web_tests\": \"190fb9ddf5997b0ff557e287e480229672314b3f\", \"boringssl_crypto_tests\": \"a10a169aa28122b4122bdeb0f64c700c8bf46507\", \"boringssl_ssl_tests\": \"bc5cd69267a8515d9e847f2b03d2067fdabc86ca\", \"browser_tests\": \"6783d1a3f6db8625a3c5282a8a25149a74b37860\", \"cacheinvalidation_unittests\": \"f2964eee9064f6349c1800d9577e0d91db078200\", \"capture_unittests\": \"adf1f20ace5f8aab7069162b72610def68dd57a6\", \"cast_unittests\": \"e5d3a38c08816e3ed6d1868a58ce87de09252ce2\", \"cc_unittests\": \"f7a1a402fd3efbf32177aea93aab17a6d133b604\", \"chrome_app_unittests\": \"95145b4285d87c013982d36feffe84420ecd12c8\", \"chromedriver_unittests\": \"52a225726e34a260daada6534ef1eccff41a9cec\", \"components_browsertests\": \"c0bbdb3d4719d6e1ce530af060e86ef6bb673dc3\", \"components_unittests\": \"0809f8fe303be55c710a5b77b87e421dab494244\", \"compositor_unittests\": \"961a4e787c7f2c8be0c28ec7b34584363aceb65b\", \"content_browsertests\": \"971eb46a5ab2e3061005523c5e909006d8e6b94e\", \"content_shell_crash_test\": \"bcad5e562e3c67090c93344d5ecef9865f66f5cd\", \"content_unittests\": \"96e68803bd4e72e2147dd9ffd85b43e01225d692\", \"crashpad_tests\": \"51b1d37b0fd48ea4473d84f994fb1d1db7c77bac\", \"cronet_tests\": \"94e8afdd9c75609b56a3820f42eeb2b5ed456475\", \"cronet_unittests\": \"7423c78141203bbe0e941df0887dc33836902d08\", \"crypto_unittests\": \"da018043f50d9d3b7a233d656e6b612f675fb53e\", \"dbus_unittests\": \"7950e48e51f7f1989f606f49f7843e1395ef832d\", \"device_unittests\": \"6ed6bc4860ee87eecad5aba3c8db0f81b42bb8af\", \"display_unittests\": \"05593e82afaf1041940fd948ffcc59081131f132\", \"events_unittests\": \"d328803cfd0198bb8766e1a0752dc5617f7e7ef3\", \"extensions_browsertests\": \"b0fa5bb46c7b7b3ff001d093805c0921c730a5d5\", \"extensions_unittests\": \"7c4e3eec0e37d014e39e24b43a8f3e6cf15588fa\", \"filesystem_service_unittests\": \"8f4c8b5b7a5b3bf1f50870d5ec24549c7c3f40f3\", \"flatbuffers_unittests\": \"ca71348ce5934d53fb892115f8e6e8a2436d5e6c\", \"gcm_unit_tests\": \"050e23333f0b0eea53bbcdf16d74b1f461ce9a78\", \"gfx_unittests\": \"f0d24f9ad3a485906119b664c671693a3df0270f\", \"gin_unittests\": \"d88d792fb58645a7c76788974137cd700b2e2b68\", \"google_apis_unittests\": \"75c588a0b9498db2e0c3a36e3d86a6f88a6ea6ac\", \"gpu_unittests\": \"7b8b422d6ccefd050ef386bd521958d1d6e2bf54\", \"grit_python_unittests\": \"8e4f4a4636015ac19fde39d2b2e52aa7150b26da\", \"gwp_asan_unittests\": \"d7fc741ea7c6bb3421d6034614fc16efa73dab04\", \"headless_browsertests\": \"742fed47e4bf17e91e3ba51d4c9de980c3224c11\", \"headless_unittests\": \"b5d2f9de85b93795598680f4d418b375fdca6b2d\", \"interactive_ui_tests\": \"0c8d14523b414fb6c7ff5b252210707c97a8160c\", \"ipc_tests\": \"b34ce07924d447901f53ae744ae89461c50e31a2\", \"jingle_unittests\": \"b5440d81deb53692297a21fa9f79e0b4d679fb66\", \"latency_unittests\": \"2172e2826ffc06a580abd081bf0b684d9a0c0004\", \"libjingle_xmpp_unittests\": \"1ff1501a51e3cef0aff232eb096c1cd5c8f7b2a1\", \"media_blink_unittests\": \"7bb8c4e10b4ef404850e6b3cd42ee93e1d03d70f\", \"media_service_unittests\": \"272d4293b246c465a0f53530ee5e0df601131d4c\", \"media_unittests\": \"c0528a7f2142b7892b110fc9a133fb4578b3d825\", \"message_center_unittests\": \"ae2143c88753232c6aee7042f9d2dff08ac108db\", \"metrics_python_tests\": \"ab6e4411719e58d25e1edb72ad080f31ada689e4\", \"midi_unittests\": \"a4f370009c8b31f248c61d6f07fde26074c35f79\", \"mojo_core_unittests\": \"aa18c7f183e2d8bdce03bd7e444209f6601b69d4\", \"mojo_unittests\": \"23415d0f1486f2c9811aa42c810af38001fa09b8\", \"nacl_helper_nonsfi_unittests\": \"df474399098f1f79b0a952efcd82c6c3d09e145c\", \"nacl_loader_unittests\": \"63ec49327e37617889087e5f3b2ebfc40184b5ff\", \"native_theme_unittests\": \"1beebb065f0d27e605f5896d7cbfa790c0593fb5\", \"net_unittests\": \"6b7350694fcf321922d443b7288e26e0098cc836\", \"pdf_unittests\": \"382a9bc1946b507b6899bfbc1e478456a2091aa5\", \"perfetto_unittests\": \"787612d83dae94926236fddc437dd2f3412907bb\", \"ppapi_unittests\": \"cc6e74e95fb092dafe0c024e3baa5c1ae1d4f194\", \"printing_unittests\": \"7e1041f5816d7f7d2953b17e25e331ef8e0b6af8\", \"remoting_unittests\": \"5f9ec8f447e006c658feffa0d20d5ffaf7dac51c\", \"sandbox_linux_unittests\": \"d89303ddbeb387c2412b01385e70a65d4e4ea8bd\", \"service_manager_unittests\": \"73cd9678561b8dd34c2424165b6dccd075da3689\", \"services_unittests\": \"b4540e57e3c8da0d142485847dcc488a4f92e793\", \"shell_dialogs_unittests\": \"d9ff18f9e2cc30fdf2d6deabf52827422b7c8b77\", \"skia_unittests\": \"47d04247f80651de7e4d509517fa6355faaf8d86\", \"snapshot_unittests\": \"fc0563ada71ef71c8c9073faa5f699bb3b13f78c\", \"sql_unittests\": \"1847f8f72ab7a055794377234dd1bd991fb3c652\", \"storage_unittests\": \"141da94b6d97408b81630f2162cf157376756ec5\", \"sync_integration_tests\": \"36f0b6a715bb48925e1705843e1a6ad3c3ffaf28\", \"telemetry_gpu_unittests\": \"7fbe12e4add8198a177f55bc5ce85cd98eac0bbb\", \"telemetry_perf_unittests\": \"b4453b80b5b0caf18e1d4a6599368dd9699904ca\", \"telemetry_unittests\": \"b1dc5eadd64d67006450727149efb602cb83027f\", \"traffic_annotation_auditor_unittests\": \"36e660fbc2ecf08337ceaf6b7413d0640f310add\", \"ui_base_unittests\": \"fbcc462a79eba3b3f1218df42b4b0f9aa64e87b8\", \"ui_touch_selection_unittests\": \"2397ef4faff2ca6eeed21ecc52f36e761ce69a58\", \"unit_tests\": \"321a0c759ddf080fee55ce912d627ae525e5c7c5\", \"url_unittests\": \"517a32b047945785664413f3d83367e7ab1c6544\", \"views_perftests\": \"b402272bf7d30b93524eeba3c2f1be55e47e6233\", \"views_unittests\": \"8995764aad0b2bbd6f5bbc91325f4434183be858\", \"viz_unittests\": \"8f4d1d2818d987043b6ed30dfa3b1c187b1701c3\", \"vr_common_unittests\": \"00f95194ccebc39efae8bd3c0342ba54931cfd2c\", \"vr_pixeltests\": \"5f898acde50e6c94de18f72ebd03347a44d4a9a2\", \"webdriver_wpt_tests\": \"04a42d073af62851bbe0b80df2f7c283494f1bf8\", \"weblayer_browsertests\": \"f3bb10dd19b8d7af664cc124516aaae4e2555fe5\", \"wm_unittests\": \"79c04f6e79b145e3142e028f4d21133757c2ed2b\", \"wtf_unittests\": \"f1c090a3a349d4b96f79f0b540bbdfcf1c8e3669\", \"xr_browser_tests\": \"1f5f796b771d4d466d0587354ec26d9d0cca2b81\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_430de131691c92065dcde51cfe816220b2f886d2d859c4fe33bcc0b34c123c61_v2\", \"builder_7d75f6999c07e42a7f7fde2313383264fbd8ed871e2008bde33b278973c1f4c8_v2\", \"builder_884aee959975e3ba239407d026a51b81c46e736b67f5763d1d4e447f2346535d_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-21-ldcp\"], \"image\": [\"chrome-xenial-20012600-8e1a7e485b7\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4809-bb2013c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1581114673195283",
"status": "COMPLETED",
"status_changed_ts": "1581117701395469",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168354",
"builder:Linux Builder (dbg)",
"buildset:commit/git/dfc72a0a7ba1f77921ba74558d792bd86da2e883",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dfc72a0a7ba1f77921ba74558d792bd86da2e883",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054505188397077120",
"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/8889075126259526336/+/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:4a3a94525af05c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1581117701399274",
"url": "https://ci.chromium.org/b/8889075126259526336",
"utcnow_ts": "1581117701781848"
}
}
[23:21:41.792] Invocation finished in 55m25.526543369s with status SUCCEEDED