[20:35:47.737] New invocation is queued and will start shortly
[20:35:48.850] Starting the invocation (attempt 1)
[20:35:48.956] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:35:48.956] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:35:48.956] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019088725079663376",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0acf7c880d0b3550601dd1b0416597d16eac0876\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9019088725079663376",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/0acf7c880d0b3550601dd1b0416597d16eac0876",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0acf7c880d0b3550601dd1b0416597d16eac0876",
"gitiles_ref:refs/heads/master"
]
}
[20:35:49.851] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614890149281714",
"id": "8853658662785365120",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0acf7c880d0b3550601dd1b0416597d16eac0876\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614890149696957",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/181124",
"builder:Linux Builder (dbg)",
"buildset:commit/git/0acf7c880d0b3550601dd1b0416597d16eac0876",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0acf7c880d0b3550601dd1b0416597d16eac0876",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019088725079663376",
"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/8853658662785365120/+/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": "1614890149697067",
"url": "https://ci.chromium.org/b/8853658662785365120",
"utcnow_ts": "1614890149844026"
}
}
[20:35:49.851] Task URL: https://ci.chromium.org/b/8853658662785365120
[20:35:49.851] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:2:0) after 1m0s
[20:36:49.880] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:2:0)
[20:36:49.880] Timer tick, asking Buildbucket for the build status
[20:36:50.990] Build 8853658662785365120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:36:50.990] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:3:0) after 1m0s
[20:37:53.653] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:3:0)
[20:37:53.653] Timer tick, asking Buildbucket for the build status
[20:37:53.817] Build 8853658662785365120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[20:37:53.817] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:4:0) after 1m0s
[20:38:41.434] Received PubSub notification, asking Buildbucket for the build status
[20:38:41.497] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:53.886] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:4:0)
[20:38:53.886] Timer tick, asking Buildbucket for the build status
[20:38:54.149] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:54.149] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:6:0) after 1m0s
[20:39:54.214] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:6:0)
[20:39:54.214] Timer tick, asking Buildbucket for the build status
[20:39:54.286] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:54.286] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:7:0) after 1m0s
[20:40:54.381] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:7:0)
[20:40:54.381] Timer tick, asking Buildbucket for the build status
[20:40:54.633] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:54.633] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:8:0) after 1m0s
[20:41:54.635] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:8:0)
[20:41:54.635] Timer tick, asking Buildbucket for the build status
[20:41:55.039] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:55.039] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:9:0) after 1m0s
[20:42:55.113] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:9:0)
[20:42:55.113] Timer tick, asking Buildbucket for the build status
[20:42:55.233] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:55.233] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:10:0) after 1m0s
[20:43:55.259] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:10:0)
[20:43:55.259] Timer tick, asking Buildbucket for the build status
[20:43:55.720] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:55.720] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:11:0) after 1m0s
[20:44:55.719] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:11:0)
[20:44:55.719] Timer tick, asking Buildbucket for the build status
[20:44:55.868] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:55.868] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:12:0) after 1m0s
[20:45:56.006] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:12:0)
[20:45:56.006] Timer tick, asking Buildbucket for the build status
[20:45:56.233] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:56.233] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:13:0) after 1m0s
[20:46:56.340] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:13:0)
[20:46:56.340] Timer tick, asking Buildbucket for the build status
[20:46:56.523] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:56.523] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:14:0) after 1m0s
[20:47:56.609] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:14:0)
[20:47:56.609] Timer tick, asking Buildbucket for the build status
[20:47:56.683] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:56.683] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:15:0) after 1m0s
[20:48:56.795] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:15:0)
[20:48:56.795] Timer tick, asking Buildbucket for the build status
[20:48:56.872] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:56.872] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:16:0) after 1m0s
[20:49:57.056] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:16:0)
[20:49:57.056] Timer tick, asking Buildbucket for the build status
[20:49:57.325] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:57.325] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:17:0) after 1m0s
[20:50:57.433] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:17:0)
[20:50:57.433] Timer tick, asking Buildbucket for the build status
[20:50:57.651] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:57.651] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:18:0) after 1m0s
[20:51:57.728] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:18:0)
[20:51:57.728] Timer tick, asking Buildbucket for the build status
[20:51:57.819] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:57.819] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:19:0) after 1m0s
[20:52:57.841] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:19:0)
[20:52:57.841] Timer tick, asking Buildbucket for the build status
[20:52:57.956] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:57.956] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:20:0) after 1m0s
[20:53:58.056] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:20:0)
[20:53:58.056] Timer tick, asking Buildbucket for the build status
[20:53:58.125] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:58.125] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:21:0) after 1m0s
[20:54:58.179] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:21:0)
[20:54:58.179] Timer tick, asking Buildbucket for the build status
[20:54:58.309] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:58.309] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:22:0) after 1m0s
[20:55:58.386] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:22:0)
[20:55:58.386] Timer tick, asking Buildbucket for the build status
[20:55:58.848] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:58.848] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:23:0) after 1m0s
[20:56:58.845] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:23:0)
[20:56:58.845] Timer tick, asking Buildbucket for the build status
[20:56:58.919] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:58.919] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:24:0) after 1m0s
[20:57:58.939] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:24:0)
[20:57:58.940] Timer tick, asking Buildbucket for the build status
[20:57:59.414] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:59.414] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:25:0) after 1m0s
[20:58:59.411] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:25:0)
[20:58:59.411] Timer tick, asking Buildbucket for the build status
[20:58:59.622] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:59.622] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:26:0) after 1m0s
[20:59:59.641] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:26:0)
[20:59:59.641] Timer tick, asking Buildbucket for the build status
[20:59:59.708] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:59.708] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:27:0) after 1m0s
[21:00:59.727] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:27:0)
[21:00:59.727] Timer tick, asking Buildbucket for the build status
[21:00:59.813] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:59.813] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:28:0) after 1m0s
[21:01:59.792] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:28:0)
[21:01:59.792] Timer tick, asking Buildbucket for the build status
[21:01:59.904] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:59.904] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:29:0) after 1m0s
[21:02:59.929] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:29:0)
[21:02:59.929] Timer tick, asking Buildbucket for the build status
[21:03:00.065] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:00.065] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:30:0) after 1m0s
[21:04:00.108] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:30:0)
[21:04:00.108] Timer tick, asking Buildbucket for the build status
[21:04:00.455] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:00.455] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:31:0) after 1m0s
[21:05:00.493] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:31:0)
[21:05:00.493] Timer tick, asking Buildbucket for the build status
[21:05:00.867] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:00.867] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:32:0) after 1m0s
[21:06:01.059] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:32:0)
[21:06:01.059] Timer tick, asking Buildbucket for the build status
[21:06:01.344] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:01.344] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:33:0) after 1m0s
[21:07:01.376] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:33:0)
[21:07:01.376] Timer tick, asking Buildbucket for the build status
[21:07:01.649] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:01.649] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:34:0) after 1m0s
[21:08:01.696] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:34:0)
[21:08:01.696] Timer tick, asking Buildbucket for the build status
[21:08:04.429] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:04.429] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:35:0) after 1m0s
[21:09:04.535] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:35:0)
[21:09:04.535] Timer tick, asking Buildbucket for the build status
[21:09:04.819] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:04.819] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:36:0) after 1m0s
[21:10:04.917] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:36:0)
[21:10:04.917] Timer tick, asking Buildbucket for the build status
[21:10:05.167] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:05.167] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:37:0) after 1m0s
[21:11:05.214] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:37:0)
[21:11:05.214] Timer tick, asking Buildbucket for the build status
[21:11:05.465] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:05.465] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:38:0) after 1m0s
[21:12:05.507] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:38:0)
[21:12:05.507] Timer tick, asking Buildbucket for the build status
[21:12:05.585] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:05.585] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:39:0) after 1m0s
[21:13:05.658] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:39:0)
[21:13:05.658] Timer tick, asking Buildbucket for the build status
[21:13:05.729] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:05.729] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:40:0) after 1m0s
[21:14:05.785] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:40:0)
[21:14:05.785] Timer tick, asking Buildbucket for the build status
[21:14:05.881] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:05.881] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:41:0) after 1m0s
[21:15:05.895] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:41:0)
[21:15:05.895] Timer tick, asking Buildbucket for the build status
[21:15:05.977] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:05.977] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:42:0) after 1m0s
[21:16:06.037] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:42:0)
[21:16:06.037] Timer tick, asking Buildbucket for the build status
[21:16:06.125] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:06.125] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:43:0) after 1m0s
[21:17:07.219] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:43:0)
[21:17:07.219] Timer tick, asking Buildbucket for the build status
[21:17:07.516] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:07.516] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:44:0) after 1m0s
[21:18:08.900] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:44:0)
[21:18:08.900] Timer tick, asking Buildbucket for the build status
[21:18:09.728] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:09.728] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:45:0) after 1m0s
[21:19:09.893] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:45:0)
[21:19:09.893] Timer tick, asking Buildbucket for the build status
[21:19:09.972] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:09.972] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:46:0) after 1m0s
[21:20:10.390] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:46:0)
[21:20:10.390] Timer tick, asking Buildbucket for the build status
[21:20:10.662] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:10.662] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:47:0) after 1m0s
[21:21:10.741] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:47:0)
[21:21:10.741] Timer tick, asking Buildbucket for the build status
[21:21:10.816] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:10.816] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:48:0) after 1m0s
[21:22:10.850] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:48:0)
[21:22:10.850] Timer tick, asking Buildbucket for the build status
[21:22:11.067] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:11.067] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:49:0) after 1m0s
[21:23:11.085] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:49:0)
[21:23:11.085] Timer tick, asking Buildbucket for the build status
[21:23:11.174] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:11.174] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:50:0) after 1m0s
[21:24:11.278] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:50:0)
[21:24:11.278] Timer tick, asking Buildbucket for the build status
[21:24:11.344] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:11.344] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:51:0) after 1m0s
[21:25:11.401] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:51:0)
[21:25:11.401] Timer tick, asking Buildbucket for the build status
[21:25:11.468] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:11.468] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:52:0) after 1m0s
[21:26:11.881] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:52:0)
[21:26:11.881] Timer tick, asking Buildbucket for the build status
[21:26:11.968] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:11.968] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:53:0) after 1m0s
[21:27:12.094] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:53:0)
[21:27:12.094] Timer tick, asking Buildbucket for the build status
[21:27:12.819] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:12.819] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:54:0) after 1m0s
[21:28:12.828] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:54:0)
[21:28:12.828] Timer tick, asking Buildbucket for the build status
[21:28:12.914] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:12.914] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:55:0) after 1m0s
[21:29:13.011] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:55:0)
[21:29:13.011] Timer tick, asking Buildbucket for the build status
[21:29:13.129] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:13.129] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:56:0) after 1m0s
[21:30:13.211] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:56:0)
[21:30:13.211] Timer tick, asking Buildbucket for the build status
[21:30:13.288] Build 8853658662785365120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:13.288] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9019088725079663376:57:0) after 1m0s
[21:30:39.708] Received PubSub notification, asking Buildbucket for the build status
[21:30:41.835] Build 8853658662785365120: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:30:41.835] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614893438428633",
"created_by": "project:chromium",
"created_ts": "1614890149281714",
"id": "8853658662785365120",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0acf7c880d0b3550601dd1b0416597d16eac0876\"}}",
"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\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"0acf7c880d0b3550601dd1b0416597d16eac0876\", \"project\": \"chromium/src\"}], \"$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-111-0n3l\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.linux\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 181124, \"got_angle_revision\": \"d0c8d6a9157ae9764d52e09ca74f315d218ba483\", \"got_dawn_revision\": \"d9a96fb28648eaff2c54beca62f14c718f042217\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"0acf7c880d0b3550601dd1b0416597d16eac0876\", \"got_revision_cp\": \"refs/heads/master@{#859912}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"6846389f25cad4233a4bd04fd3fc6c60ae30ff35\", \"got_v8_revision\": \"5134dc63648b2cf1d5f95604dc1d87520f121443\", \"got_v8_revision_cp\": \"refs/heads/9.1.44@{#1}\", \"got_webrtc_revision\": \"3208bf102b75e8ec1eaa6640dfa3b545310b1e02\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33382}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.linux:linux-clang-tidy-dbg\", \"tryserver.chromium.linux:linux_chromium_dbg_ng\", \"tryserver.chromium.linux:linux_chromium_compile_dbg_ng\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0acf7c880d0b3550601dd1b0416597d16eac0876\", \"swarm_hashes_refs/heads/master(at){#859912}_without_patch\": {\"absl_hardening_tests\": \"f5fda5169fa357569d304f9d04fb1fe59ae443d9\", \"accessibility_unittests\": \"66c1f754db106014b9684c1943c725ccbb05df53\", \"angle_unittests\": \"2bc763ab29f9ca9f4d4a4ffd65ac3b8446185ff7\", \"app_shell_unittests\": \"b7a4e9a279383d8603a4541e1977b50d4353441d\", \"aura_unittests\": \"d575d6aba0abe7e4ae773b0a93888289fa81c7c5\", \"base_unittests\": \"c5e7bef93ddfa8d64ad8b2c3392f764863d2bb69\", \"base_util_unittests\": \"02e9e76e3b56f0e063542303ede73a49b3cef53b\", \"blink_common_unittests\": \"6fb9b424a3c0f98453382a85662c3b8aa4b543e9\", \"blink_fuzzer_unittests\": \"f1639734514e82d66d41fa45ad554440153e6147\", \"blink_heap_unittests\": \"a7b645c6c38ed09af61774c7748b95a97a56409f\", \"blink_platform_unittests\": \"2781a21e43a46d82f73eb4c95da64d10e9bc550f\", \"blink_python_tests\": \"5abf5e16cf97a1d15238e157491a997927e8bd17\", \"blink_unittests\": \"a05e64d7dbf6d5908c5141ae00cd894a5a815802\", \"blink_web_tests\": \"2bfeba6dbf6b17727d05d92a69843712bd579332\", \"boringssl_crypto_tests\": \"1057885bd7fac4e10f6df7ca0a1d26b5df7be3b7\", \"boringssl_ssl_tests\": \"07b9d8a3be7adb62948a6610c56f0960107f937e\", \"browser_tests\": \"f8e3640959447d7d192dcb58254892a829fb5acc\", \"capture_unittests\": \"cd394596200d58786ea3b483cdcd3bc23e32dc97\", \"cast_unittests\": \"e1d803d8bce2c0b3019ee4c4ba88930753dd9c17\", \"cc_unittests\": \"88a40b7b4b8b7746787ce86f908d0af75b2516b3\", \"chrome_app_unittests\": \"d758821d09959489068b87e5c32b190d891a2504\", \"chromedriver_unittests\": \"c84e3b8a94f7a694b0292a686e9bc9e8c32c3199\", \"color_unittests\": \"adafbb4b7c13aaab7476313f46e696b55d1eaf5f\", \"components_browsertests\": \"5cf6023669599b3391837296e232f5c597689560\", \"components_unittests\": \"f665b6aa70d47906b9fc0786ed3d4d95ada7bcf1\", \"compositor_unittests\": \"29ff4787bcbcd6c8b149c896b38f2f9e1369d271\", \"content_browsertests\": \"ac321211e4792d7e40039cbd2eec5893b527aa03\", \"content_shell_crash_test\": \"184fe1614f97b53d9b47bb871fbedd0d1e9f6a01\", \"content_unittests\": \"8417271ddf80449707203ffa00751b4641476317\", \"crashpad_tests\": \"1fea8f483e3802ada2714c2e08fe24f03fd02e0a\", \"cronet_tests\": \"abfc78c9514dd34d6a83b83d1e94a9c84340c18c\", \"cronet_unittests\": \"c211439e0305ea5bb824a3863b83f3db2f7c4a03\", \"crypto_unittests\": \"0efa5e0e31a565adc7140dfb001781bbe5bbac7f\", \"dbus_unittests\": \"7a4b92d96fbc6ccb1a447f75b2ec53668dc6ed0b\", \"device_unittests\": \"a6f461d5daecc854b4c1ba5e97e9296a273f6ae1\", \"display_unittests\": \"ed28dc2a5d9fdfe3cfd812d8022b297e60efc768\", \"events_unittests\": \"96d50bdfbd9626426596905564c19e25b6320961\", \"extensions_browsertests\": \"4d9178d089f56fc6e1cc706ad2942bc27aa4cf9d\", \"extensions_unittests\": \"36453a8f93a6c54aafc9e3c0c14effab849fd83d\", \"filesystem_service_unittests\": \"5216ce6edeba77e392f385d384e2e8d9e6c2f063\", \"flatbuffers_unittests\": \"123fc665b859d2862b8d14757814952110df3994\", \"gcm_unit_tests\": \"9739316bfada0e2372569952955fd3b08eff9915\", \"gfx_unittests\": \"cf186058eba7edb3cd33f49732cebd2488a83c77\", \"gin_unittests\": \"d504b511ac75bfb4ba5ffc3df930f13f0196922d\", \"google_apis_unittests\": \"4880b0779f5350528388578c584f141925bf817b\", \"gpu_unittests\": \"260dc19ebe6a9d3d19082ee2bbab680a85e34a3e\", \"grit_python_unittests\": \"f3a4430dabc578f6d6fa58cb9ef7264f3f78d825\", \"gwp_asan_unittests\": \"f4234b02575797ad1625a236b3728d867786af27\", \"headless_browsertests\": \"719849064938a939fe19e9ef64879ac17f7fb601\", \"headless_unittests\": \"12a415a9c7068c6850c326eb06dbcd6bce7d6393\", \"interactive_ui_tests\": \"38c1c7714cf44bfea771038bc7d55c46e2659e22\", \"ipc_tests\": \"b1d11cbdb08e86967fceb000beecad79d0d576df\", \"jingle_unittests\": \"ac237d959841642e853b0bbd5cae2edeae12b030\", \"latency_unittests\": \"8df0ea3da123a74e89b160af449b2eee6ff90d7c\", \"libjingle_xmpp_unittests\": \"2e32037058fb23858d8a6c85320dec11d1c67070\", \"liburlpattern_unittests\": \"9090da7c4853697532991c79c25c325caf71bf87\", \"media_blink_unittests\": \"0733cca70c41dd610c26e371d28203a6a3f46b3c\", \"media_unittests\": \"d8b83b9f5808281cf406c99ab0a69ef79952bad2\", \"message_center_unittests\": \"0e7a132ea61e96adbc03fb14746c66a6b770aa50\", \"metrics_python_tests\": \"e1ab08b26e2f797936bc722204af5b1525941ab9\", \"midi_unittests\": \"7676cc7f56b1dd7307d0f75dfc19fa82fbce07ef\", \"mojo_core_unittests\": \"80797e7c9d87b2b5485bfa9faacae7884eb05046\", \"mojo_unittests\": \"67b6b709a4d83a701bad5574876301bf4c56d7e5\", \"nacl_helper_nonsfi_unittests\": \"cb4e9e2e280c7dd4b3f0c4adb31ee9bbcd056080\", \"nacl_loader_unittests\": \"513088095a70ea9f09520ba403006c862bcea8eb\", \"native_theme_unittests\": \"f73941d77cee39a4d6a7cfc4fd4ccdafb4d42fe7\", \"net_unittests\": \"6173a4d861e77e8d25d3be375a09fcaecf80e935\", \"openscreen_unittests\": \"63a7a04641596a807e0788984701eef485fa5322\", \"pdf_unittests\": \"446ed4e9ab80cd073cdc52536a5e937dca7e6bb5\", \"perfetto_unittests\": \"6bc4148329cdde84d3e428868d7e3b450cf591ca\", \"ppapi_unittests\": \"12bbac16a47d3f7b3a8cdcf444976c48bc870546\", \"printing_unittests\": \"ab12d0f9acabe6136db9c2421cc2f11c311a7b30\", \"remoting_unittests\": \"ca09f549c3c82b28f70d914c984244a13a858784\", \"sandbox_linux_unittests\": \"468dcfa8457d4858eb2328b026a51d59d428d7ca\", \"service_manager_unittests\": \"5dae491c74b3649094d845745b96658e60f5676c\", \"services_unittests\": \"b364d0ab8a7c07ecae63ffb948d803030b0b9243\", \"shell_dialogs_unittests\": \"a479ac192bbe5a39db019f40fe2dc7baf48f7449\", \"skia_unittests\": \"5a8cbc0c88af05e924321f6ec41c10ee678bfefe\", \"snapshot_unittests\": \"f83b7556c51470357c9459c44f5cbfc3d74c2c16\", \"sql_unittests\": \"0697e2fa2666f5feec82f945314f78cd4c4d63e8\", \"storage_unittests\": \"44bfda06ea291b651bf01b2dfaab95f55ac10b0b\", \"sync_integration_tests\": \"fde88b53b3bf2168cbe13e6df62e39906b982091\", \"telemetry_gpu_unittests\": \"3c64e555510d60f8ba34368f2018b05c0b68218f\", \"telemetry_perf_unittests\": \"16ead3e660dbe5ac765d2294533d85d0683d7de6\", \"telemetry_unittests\": \"5f4a3445b07cbbd6813f5459b6bb04073c8e1133\", \"traffic_annotation_auditor_unittests\": \"96078db6f67b237598a1f8ec8abb740217359f71\", \"ui_base_unittests\": \"811d070951e9cfaa5325d75d1d7d329e93a33f89\", \"ui_touch_selection_unittests\": \"7e4cadb5fc07af3bacb38b44eb8e8fde3f414d12\", \"unit_tests\": \"f175f828ed8f3a5cdf3e05e2cc4fc8049045b43c\", \"url_unittests\": \"36abf5fac037f6930cdaba25d31f1ae2d0f1109b\", \"views_perftests\": \"d3532313b89b3b1fae0deefaf39b2dfe6df2f9a5\", \"views_unittests\": \"e98920a6383dba44cf64bc131a0df490adc1e7b8\", \"viz_unittests\": \"4b136d6c33e0b94761f0b9ee9df06f2c44ab3347\", \"vr_common_unittests\": \"b3586b3880bfc8101dab0d531f890655942a1210\", \"vr_pixeltests\": \"5797595bd0fd096f27a71a21879c0360f232b50f\", \"webdriver_wpt_tests\": \"b649dfc74c37e4df7b94043c4cdf9e6e102a1f0a\", \"weblayer_browsertests\": \"64542a89d3822364f2fa5807c146785f07890a72\", \"weblayer_unittests\": \"17054156acf2c35b710392be8a6d00cbc997689b\", \"wm_unittests\": \"3492d8560360beed586c2cddcb9d62ca8010d278\", \"wtf_unittests\": \"0b6baa6b62e894fc91028f376f96e572e0cc65ab\", \"x11_unittests\": \"475a49f8c41a23e9304ee5a6190891d5388d85f9\", \"xr_browser_tests\": \"570065f9f2a6ca2cba5f89c9976550b5e5c8bfcb\", \"zlib_unittests\": \"cee0a75440ce21f9faaa0455919226f35007973c\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1143bf8a0c86d96d189f99a4f0f749b4f3d1e46c849c82cdd2c6ebe168938b3a_v2\", \"builder_9abb531f9fa28fcfd3ce68b2370bf6d67201ddb083beddbd2671f7c5fe25be9e_v2\", \"builder_a10052c67097968898fa29710cdaa0ada58a120001760a536a006ca29eefe964_v2\", \"builder_a15c792d02d243f2865d956ab51326d361a8fcfed3c44b30d209df7676d4718c_v2\", \"builder_ce22d0fc5c297d03141230de59f52ebaf9005395a667e8b14df59e499796620f_v2\", \"builder_d2010e0a6a440fb89731b061caf7d596c92723d209ffefd62489cc037db4f7a7_v2\", \"builder_e21c17032b725bcf1c922976a36352546baf3f39ec148d1e6730a93c0400d8cc_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-111-0n3l\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5725-1a0d85e\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1614890319760819",
"status": "COMPLETED",
"status_changed_ts": "1614893439330923",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/181124",
"builder:Linux Builder (dbg)",
"buildset:commit/git/0acf7c880d0b3550601dd1b0416597d16eac0876",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0acf7c880d0b3550601dd1b0416597d16eac0876",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019088725079663376",
"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/8853658662785365120/+/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:5217c5b035915110",
"user_agent:luci-scheduler"
],
"updated_ts": "1614893439335785",
"url": "https://ci.chromium.org/b/8853658662785365120",
"utcnow_ts": "1614893439891627"
}
}
[21:30:41.835] Invocation finished in 54m54.124995155s with status SUCCEEDED