[03:27:22.236] New invocation is queued and will start shortly
[03:27:23.337] Starting the invocation (attempt 1)
[03:27:23.371] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:27:23.371] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:27:23.371] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9054214453976012992",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"1f8cd1267d8088a73140329516367b84f32d44f5\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9054214453976012992",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/1f8cd1267d8088a73140329516367b84f32d44f5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1f8cd1267d8088a73140329516367b84f32d44f5",
"gitiles_ref:refs/heads/master"
]
}
[03:27:24.287] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1581391643494601",
"id": "8888784391989589856",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1f8cd1267d8088a73140329516367b84f32d44f5\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1581391643961502",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168470",
"builder:Linux Builder (dbg)",
"buildset:commit/git/1f8cd1267d8088a73140329516367b84f32d44f5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1f8cd1267d8088a73140329516367b84f32d44f5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054214453976012992",
"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/8888784391989589856/+/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": "1581391643962102",
"url": "https://ci.chromium.org/b/8888784391989589856",
"utcnow_ts": "1581391644276589"
}
}
[03:27:24.287] Task URL: https://ci.chromium.org/b/8888784391989589856
[03:27:24.287] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:2:0) after 1m0s
[03:28:24.408] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:2:0)
[03:28:24.408] Timer tick, asking Buildbucket for the build status
[03:28:24.582] Build 8888784391989589856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:28:24.582] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:3:0) after 1m0s
[03:29:24.683] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:3:0)
[03:29:24.683] Timer tick, asking Buildbucket for the build status
[03:29:26.485] Build 8888784391989589856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:29:26.485] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:4:0) after 1m0s
[03:30:26.693] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:4:0)
[03:30:26.693] Timer tick, asking Buildbucket for the build status
[03:30:27.305] Build 8888784391989589856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:30:27.305] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:5:0) after 1m0s
[03:31:27.517] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:5:0)
[03:31:27.517] Timer tick, asking Buildbucket for the build status
[03:31:27.651] Build 8888784391989589856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:31:27.651] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:6:0) after 1m0s
[03:32:06.781] Received PubSub notification, asking Buildbucket for the build status
[03:32:07.991] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:27.758] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:6:0)
[03:32:27.758] Timer tick, asking Buildbucket for the build status
[03:32:28.120] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:28.120] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:8:0) after 1m0s
[03:33:28.231] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:8:0)
[03:33:28.231] Timer tick, asking Buildbucket for the build status
[03:33:28.472] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:28.472] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:9:0) after 1m0s
[03:34:28.598] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:9:0)
[03:34:28.598] Timer tick, asking Buildbucket for the build status
[03:34:29.147] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:29.147] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:10:0) after 1m0s
[03:35:29.166] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:10:0)
[03:35:29.166] Timer tick, asking Buildbucket for the build status
[03:35:29.368] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:29.368] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:11:0) after 1m0s
[03:36:29.480] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:11:0)
[03:36:29.480] Timer tick, asking Buildbucket for the build status
[03:36:29.833] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:29.833] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:12:0) after 1m0s
[03:37:29.881] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:12:0)
[03:37:29.881] Timer tick, asking Buildbucket for the build status
[03:37:30.018] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:30.018] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:13:0) after 1m0s
[03:38:30.034] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:13:0)
[03:38:30.034] Timer tick, asking Buildbucket for the build status
[03:38:30.274] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:30.274] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:14:0) after 1m0s
[03:39:30.336] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:14:0)
[03:39:30.336] Timer tick, asking Buildbucket for the build status
[03:39:30.730] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:30.730] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:15:0) after 1m0s
[03:40:30.818] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:15:0)
[03:40:30.818] Timer tick, asking Buildbucket for the build status
[03:40:30.928] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:30.928] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:16:0) after 1m0s
[03:41:30.989] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:16:0)
[03:41:30.989] Timer tick, asking Buildbucket for the build status
[03:41:31.188] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:31.188] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:17:0) after 1m0s
[03:42:31.212] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:17:0)
[03:42:31.212] Timer tick, asking Buildbucket for the build status
[03:42:31.379] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:31.379] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:18:0) after 1m0s
[03:43:31.375] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:18:0)
[03:43:31.375] Timer tick, asking Buildbucket for the build status
[03:43:31.512] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:31.512] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:19:0) after 1m0s
[03:44:31.563] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:19:0)
[03:44:31.563] Timer tick, asking Buildbucket for the build status
[03:44:31.721] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:31.721] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:20:0) after 1m0s
[03:45:31.789] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:20:0)
[03:45:31.789] Timer tick, asking Buildbucket for the build status
[03:45:32.510] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:32.510] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:21:0) after 1m0s
[03:46:32.568] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:21:0)
[03:46:32.568] Timer tick, asking Buildbucket for the build status
[03:46:32.733] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:32.733] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:22:0) after 1m0s
[03:47:32.765] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:22:0)
[03:47:32.765] Timer tick, asking Buildbucket for the build status
[03:47:33.660] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:33.660] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:23:0) after 1m0s
[03:48:33.696] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:23:0)
[03:48:33.696] Timer tick, asking Buildbucket for the build status
[03:48:34.122] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:34.122] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:24:0) after 1m0s
[03:49:34.145] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:24:0)
[03:49:34.145] Timer tick, asking Buildbucket for the build status
[03:49:34.311] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:34.311] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:25:0) after 1m0s
[03:50:34.398] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:25:0)
[03:50:34.398] Timer tick, asking Buildbucket for the build status
[03:50:34.757] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:34.757] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:26:0) after 1m0s
[03:51:34.843] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:26:0)
[03:51:34.843] Timer tick, asking Buildbucket for the build status
[03:51:35.114] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:35.114] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:27:0) after 1m0s
[03:52:35.138] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:27:0)
[03:52:35.138] Timer tick, asking Buildbucket for the build status
[03:52:35.330] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:35.330] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:28:0) after 1m0s
[03:53:35.398] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:28:0)
[03:53:35.398] Timer tick, asking Buildbucket for the build status
[03:53:35.682] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:35.682] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:29:0) after 1m0s
[03:54:35.777] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:29:0)
[03:54:35.777] Timer tick, asking Buildbucket for the build status
[03:54:36.667] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:36.667] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:30:0) after 1m0s
[03:55:36.796] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:30:0)
[03:55:36.796] Timer tick, asking Buildbucket for the build status
[03:55:36.979] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:36.979] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:31:0) after 1m0s
[03:56:37.166] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:31:0)
[03:56:37.166] Timer tick, asking Buildbucket for the build status
[03:56:37.876] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:37.876] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:32:0) after 1m0s
[03:57:37.928] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:32:0)
[03:57:37.928] Timer tick, asking Buildbucket for the build status
[03:57:38.752] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:38.752] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:33:0) after 1m0s
[03:58:38.858] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:33:0)
[03:58:38.858] Timer tick, asking Buildbucket for the build status
[03:58:39.297] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:39.297] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:34:0) after 1m0s
[03:59:39.387] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:34:0)
[03:59:39.387] Timer tick, asking Buildbucket for the build status
[03:59:39.538] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:39.538] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:35:0) after 1m0s
[04:00:39.567] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:35:0)
[04:00:39.567] Timer tick, asking Buildbucket for the build status
[04:00:39.827] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:39.828] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:36:0) after 1m0s
[04:01:39.849] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:36:0)
[04:01:39.849] Timer tick, asking Buildbucket for the build status
[04:01:40.065] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:40.065] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:37:0) after 1m0s
[04:02:40.091] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:37:0)
[04:02:40.091] Timer tick, asking Buildbucket for the build status
[04:02:40.487] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:40.487] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:38:0) after 1m0s
[04:03:40.551] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:38:0)
[04:03:40.551] Timer tick, asking Buildbucket for the build status
[04:03:40.745] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:40.745] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:39:0) after 1m0s
[04:04:40.773] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:39:0)
[04:04:40.773] Timer tick, asking Buildbucket for the build status
[04:04:42.167] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:42.167] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:40:0) after 1m0s
[04:05:42.177] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:40:0)
[04:05:42.177] Timer tick, asking Buildbucket for the build status
[04:05:43.151] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:43.151] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:41:0) after 1m0s
[04:06:43.204] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:41:0)
[04:06:43.204] Timer tick, asking Buildbucket for the build status
[04:06:45.616] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:45.616] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:42:0) after 1m0s
[04:07:45.643] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:42:0)
[04:07:45.643] Timer tick, asking Buildbucket for the build status
[04:07:46.209] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:46.209] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:43:0) after 1m0s
[04:08:46.264] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:43:0)
[04:08:46.264] Timer tick, asking Buildbucket for the build status
[04:08:46.445] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:46.445] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:44:0) after 1m0s
[04:09:46.499] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:44:0)
[04:09:46.499] Timer tick, asking Buildbucket for the build status
[04:09:47.812] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:47.812] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:45:0) after 1m0s
[04:10:48.009] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:45:0)
[04:10:48.009] Timer tick, asking Buildbucket for the build status
[04:10:48.155] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:48.156] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:46:0) after 1m0s
[04:11:48.315] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:46:0)
[04:11:48.315] Timer tick, asking Buildbucket for the build status
[04:11:48.457] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:48.457] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:47:0) after 1m0s
[04:12:48.577] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:47:0)
[04:12:48.577] Timer tick, asking Buildbucket for the build status
[04:12:49.416] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:49.416] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:48:0) after 1m0s
[04:13:49.422] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:48:0)
[04:13:49.422] Timer tick, asking Buildbucket for the build status
[04:13:49.588] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:49.588] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:49:0) after 1m0s
[04:14:49.630] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:49:0)
[04:14:49.630] Timer tick, asking Buildbucket for the build status
[04:14:49.838] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:49.838] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:50:0) after 1m0s
[04:15:49.951] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:50:0)
[04:15:49.951] Timer tick, asking Buildbucket for the build status
[04:15:50.275] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:50.275] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:51:0) after 1m0s
[04:16:50.318] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:51:0)
[04:16:50.318] Timer tick, asking Buildbucket for the build status
[04:16:51.027] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:51.027] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:52:0) after 1m0s
[04:17:51.090] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:52:0)
[04:17:51.090] Timer tick, asking Buildbucket for the build status
[04:17:51.901] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:51.901] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:53:0) after 1m0s
[04:18:52.059] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:53:0)
[04:18:52.059] Timer tick, asking Buildbucket for the build status
[04:18:52.507] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:52.507] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:54:0) after 1m0s
[04:19:52.538] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:54:0)
[04:19:52.538] Timer tick, asking Buildbucket for the build status
[04:19:52.871] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:52.871] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:55:0) after 1m0s
[04:20:53.023] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:55:0)
[04:20:53.023] Timer tick, asking Buildbucket for the build status
[04:20:53.202] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:53.202] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:56:0) after 1m0s
[04:21:53.261] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:56:0)
[04:21:53.261] Timer tick, asking Buildbucket for the build status
[04:21:55.245] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:55.245] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:57:0) after 1m0s
[04:22:55.272] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:57:0)
[04:22:55.272] Timer tick, asking Buildbucket for the build status
[04:22:55.521] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:55.521] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:58:0) after 1m0s
[04:23:55.602] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:58:0)
[04:23:55.602] Timer tick, asking Buildbucket for the build status
[04:23:58.048] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:58.048] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:59:0) after 1m0s
[04:24:58.141] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:59:0)
[04:24:58.141] Timer tick, asking Buildbucket for the build status
[04:24:58.292] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:58.292] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:60:0) after 1m0s
[04:25:58.317] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:60:0)
[04:25:58.317] Timer tick, asking Buildbucket for the build status
[04:25:58.795] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:58.795] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:61:0) after 1m0s
[04:26:58.894] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:61:0)
[04:26:58.894] Timer tick, asking Buildbucket for the build status
[04:26:59.048] Build 8888784391989589856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:59.048] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054214453976012992:62:0) after 1m0s
[04:27:06.745] Received PubSub notification, asking Buildbucket for the build status
[04:27:07.461] Build 8888784391989589856: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:27:07.461] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1581395225966239",
"created_by": "project:chromium",
"created_ts": "1581391643494601",
"id": "8888784391989589856",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1f8cd1267d8088a73140329516367b84f32d44f5\"}}",
"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-63-r2hp\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168470, \"got_angle_revision\": \"b40d48a67c932625f349c6e58036c2d96b62489b\", \"got_dawn_revision\": \"713d9cbf939e525e025a69a17bda0bf51b449c47\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"1f8cd1267d8088a73140329516367b84f32d44f5\", \"got_revision_cp\": \"refs/heads/master@{#740170}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"196d81774ef86d1c6c2d028dd5407b47eaa51a99\", \"got_v8_revision\": \"d0da7b4db4d335d91a374ec3be53199c482e7174\", \"got_v8_revision_cp\": \"refs/heads/8.2.56@{#1}\", \"got_webrtc_revision\": \"d4c3c3a454215b21f02d2a772a729b4c87d6b33e\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30486}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1f8cd1267d8088a73140329516367b84f32d44f5\", \"swarm_hashes_refs/heads/master(at){#740170}_without_patch\": {\"accessibility_unittests\": \"d3a8ec60c1320b7471954156422e1ee75467c60a\", \"angle_unittests\": \"45d49712fb055a6351f209eeb3aeb298900ed9ac\", \"app_shell_unittests\": \"1c1dd4270b164b42e5a4b105a45a2225936c34da\", \"aura_unittests\": \"d9513e272a053e71bcd582152aee42e4edb044da\", \"base_unittests\": \"dd71979f80f08586866a1ccc5aa3716aadea39e5\", \"base_util_unittests\": \"c05025d6be5cb337a3dbd3063e68ed2a1628689d\", \"blink_common_unittests\": \"ef19be0ca10d044b5da32564206112526cf695f7\", \"blink_fuzzer_unittests\": \"57a30ed090fecd4ce2aeb41286da482dab1ec214\", \"blink_heap_unittests\": \"83c0999bcd3bb64d5d3de361c1ed6e9176343a9f\", \"blink_platform_unittests\": \"5a629fcac7afb9eb4db226e0bfe35bfeb76d8c1f\", \"blink_python_tests\": \"a7d4ae6fddeede66e042b5e0a9f31c471d76fba8\", \"blink_unittests\": \"517cdf6be4efb850b47cb35441085db63f29a599\", \"blink_web_tests\": \"cf03e3eb717fc64d2985f56a95757833bd23e2a5\", \"boringssl_crypto_tests\": \"95e11b3ad1485ef5ca655ac0b86d8ff3dba78047\", \"boringssl_ssl_tests\": \"8866b6c4ad6d38ade44b9b37bae3ea235aadd8cc\", \"browser_tests\": \"a54e66aad2d1e21bc3670bc1b12f71f652b72a5d\", \"cacheinvalidation_unittests\": \"1d10521a9370a4731b63a49784b78475af69f943\", \"capture_unittests\": \"6aea8dd8161f758026ca04c76a640394f7c2e41e\", \"cast_unittests\": \"ffdf85ed9fe08005116022c34706dd82254f0dbf\", \"cc_unittests\": \"39a0f57dced8c9aab3b74c2c5e2fcb10e4b61cae\", \"chrome_app_unittests\": \"3978634839cf4cdc06b876b96ff303f5eb130e83\", \"chromedriver_unittests\": \"86298faf329595b3ad6c6ee1a008c6888cb1973b\", \"components_browsertests\": \"5bd5bdf390b5bf79f79e64dafcdd5f96cee1ac50\", \"components_unittests\": \"016d0a8e0fd937715abae04b853cd28a63fba682\", \"compositor_unittests\": \"2d93bca3742b89f9161f573436746e0e3aa7e75a\", \"content_browsertests\": \"4bd6db18f57fc9aafcf224896fd3f532a365e450\", \"content_shell_crash_test\": \"00934f2158f0c3457bdfbe30fb41b058c33f6b9b\", \"content_unittests\": \"8222237a2725f3fdc6a9a785997063fb6f4887bb\", \"crashpad_tests\": \"023803b06a5c1d9219866baadc639dd403479e66\", \"cronet_tests\": \"7933193c629e4b4d072ada3e3a4590abb0304b32\", \"cronet_unittests\": \"23cbcd019961bf3ada6c7862d318b3737e055b9f\", \"crypto_unittests\": \"d22b6be1d474b603123318a330a4f75492406c0b\", \"dbus_unittests\": \"c6c5b33b5c4c1bd03bc012e23724f7a0b2e043ce\", \"device_unittests\": \"13cddf69bcfa62387c85ce66f5bc31e325d08f57\", \"display_unittests\": \"290caffa0f41e6797fe27256bcd46d6809a8e92e\", \"events_unittests\": \"38e0207d16b0b6bbadde84e264bb50186ace0193\", \"extensions_browsertests\": \"1b399868288629133862dbf5f95e53a28963a611\", \"extensions_unittests\": \"15f7fda5e1a9aa75474c6e81d35e4a5606d8b884\", \"filesystem_service_unittests\": \"8b362cdc42dead66990593904a9b647d78f4682c\", \"flatbuffers_unittests\": \"9eab69acab0f6bd1460710d9ac6e8475de73625c\", \"gcm_unit_tests\": \"2eba334b4a99266435dc1fb2b03b80148343daeb\", \"gfx_unittests\": \"0886355bd4dea3d25d121481345fee117c94d426\", \"gin_unittests\": \"660e6221e90e5b24fdfd4797224b2ccce057d423\", \"google_apis_unittests\": \"b5eebefdb600d84a902aa8ed21ae85863ba9b5cb\", \"gpu_unittests\": \"6d974bd2474ac625bce6fe23e085ebd6aa38ca69\", \"grit_python_unittests\": \"2c197a1bd065e31b00ed1c7636679f90a901aabe\", \"gwp_asan_unittests\": \"6fba2dccc1827b6afc3e6376d168a769b8d93843\", \"headless_browsertests\": \"03d845dc5263ede3fe24a58af9dd37e6bcda78bb\", \"headless_unittests\": \"4f20f1c563f132a82e7fdf40d686fb312f454c36\", \"interactive_ui_tests\": \"5d873cf2599c0c257b05892b140326c1abdd15c8\", \"ipc_tests\": \"f3d5a29fe966506f19b2ce45db8aa74b12d03d18\", \"jingle_unittests\": \"143cd694db8ea752ae599a835e438448227dfc2f\", \"latency_unittests\": \"e125ccb0b128b5a214f6ef5270aabebda3ac0018\", \"libjingle_xmpp_unittests\": \"e9a128a14f8c998fe9e82a43f7418dafac36e1a3\", \"media_blink_unittests\": \"08c2394cd6d695e44b0ba2ef72384be7f54f6ab4\", \"media_service_unittests\": \"646efd90fec1c4ed2da0d2cdeaa639afb22dea6c\", \"media_unittests\": \"23f55dd29793aa15616b3a18307bb86a9ead728f\", \"message_center_unittests\": \"fca7a2cd7f37dd25109d7ee7dce97703819ab745\", \"metrics_python_tests\": \"65a127551ef9b17777013d30b75676d4b08c80b1\", \"midi_unittests\": \"8e14b45d72fa7b3de17fc06a8b113af6d0328d72\", \"mojo_core_unittests\": \"f88b9a3addfffc8a12d94a8ed58ea0f43f9a433a\", \"mojo_unittests\": \"3f2de83a9e66955fbdf10873bd4b33b27db2431c\", \"nacl_helper_nonsfi_unittests\": \"2ebd60291f651fd7b65f4c47daa251c4ae4148b0\", \"nacl_loader_unittests\": \"234db8eb35fe564cccc9e3ec6ce7f15d1dd8575d\", \"native_theme_unittests\": \"86f3370850bbe2585fa7c7c76f74b41758b6eeff\", \"net_unittests\": \"c41696d785b8ec90beb4a9930279d47a649ec7bc\", \"pdf_unittests\": \"2f60a811e3c900a01753520b5af172c0cf9c0109\", \"perfetto_unittests\": \"3ee544ec1f12955df0e818e3bb6a4ee4e63f4805\", \"ppapi_unittests\": \"2ce97e7974c55c77066e0d1ee7d1e9fcc04a3ce2\", \"printing_unittests\": \"df7827375784c253a71659a0b76a28ea24818895\", \"remoting_unittests\": \"9e7561cfa6a39d4b588a913076e724dbaf8d1663\", \"sandbox_linux_unittests\": \"e8d4ed764c6c7c4768ad6000eb2e73ddb502bc6a\", \"service_manager_unittests\": \"c3af9d470fd778818d9883140a2558a1959e24d6\", \"services_unittests\": \"d58c4cd5ef8f250d396a3f9dd7fce8d02f589235\", \"shell_dialogs_unittests\": \"511ef0cd8aae89336090efffd9eccbcea37a339d\", \"skia_unittests\": \"54989ea509592839cc0391972c4077468e0e2f37\", \"snapshot_unittests\": \"1a07a40dc05ba597380971675c85d7aec1793c61\", \"sql_unittests\": \"1d3e703df90a5bbda98999360d9009e9aefeebd2\", \"storage_unittests\": \"6db762caa623684aa529344b0a0587b642282873\", \"sync_integration_tests\": \"68700997505184910bf8e44f8477cf994134d5ae\", \"telemetry_gpu_unittests\": \"3c003687287b7e4d86939486eca3a632f84361d3\", \"telemetry_perf_unittests\": \"98c47dcbddc0f83e2cf9a805e775910fdadb778d\", \"telemetry_unittests\": \"7d419a57123a66e64edf40523439300651992aef\", \"traffic_annotation_auditor_unittests\": \"e5d92524611892f7593cc41a33435f7b10b6b859\", \"ui_base_unittests\": \"35ecd7312e9a7b322f28a90c4b20ddfca3a06aef\", \"ui_touch_selection_unittests\": \"4ca7445dc1ffef2bc218f4bcd43aa2bd54b28aa0\", \"unit_tests\": \"8f9755ada4d1f62adc9a6c09a7cdb037735c9b40\", \"url_unittests\": \"62f67d8b7464581607fdcdb3ddd0ce2741955703\", \"views_perftests\": \"3812e1c8fc6af12416dc9367c7dc83fa2048e962\", \"views_unittests\": \"5de25ade63eb22ff58d0fd7783272c233c0ab446\", \"viz_unittests\": \"c76f0f19b3a4bd4773bca30b1594cc94d951d6c5\", \"vr_common_unittests\": \"4a887fdfd5ef0f0422c3eff14dea081d6a97c4ca\", \"vr_pixeltests\": \"ef59f720762c31810156fb9d0cad8c1d8d462457\", \"webdriver_wpt_tests\": \"c65b5222b6788fea15cb5cbe7c3f0ec87e8eb62e\", \"weblayer_browsertests\": \"d6c548483d7ba79060820b900b5fe7c5900d8ee4\", \"wm_unittests\": \"e76f0c77d492c3bc48693fe378a5752c34972bd4\", \"wtf_unittests\": \"e341a1c333ad2ff10c44c657438eb769f2914811\", \"xr_browser_tests\": \"5288b067c15b84e44ba38b19fe797de794cb8eb7\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_3f701152d865ce3ec8feb15f973214ad980aeace5cbda24477548a965269fca6_v2\", \"builder_a1b9c6892397d61c3624774723419f5ae48fcf776e7d2304bd4682bf5cd4c049_v2\", \"builder_d94bc43a1bd6073c4720097d771b0eb8c5098b1a53a2f9884a7e1b0af643c636_v2\", \"builder_f5174a4d80cdc4c8aa1989fa45d69979d28910f768af140dd646791c34143bfe_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-63-r2hp\"], \"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\": [\"4812-ea7e8fc\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1581391925953111",
"status": "COMPLETED",
"status_changed_ts": "1581395226378942",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168470",
"builder:Linux Builder (dbg)",
"buildset:commit/git/1f8cd1267d8088a73140329516367b84f32d44f5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1f8cd1267d8088a73140329516367b84f32d44f5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054214453976012992",
"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/8888784391989589856/+/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:4a4b1b0f14674810",
"user_agent:luci-scheduler"
],
"updated_ts": "1581395226382548",
"url": "https://ci.chromium.org/b/8888784391989589856",
"utcnow_ts": "1581395227436789"
}
}
[04:27:07.461] Invocation finished in 59m45.244485885s with status SUCCEEDED