[01:30:24.320] New invocation is queued and will start shortly
[01:30:25.927] Starting the invocation (attempt 1)
[01:30:26.042] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:30:26.042] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:30:26.042] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018526608029266144",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"e0534f38e87b9d51646572a1ddf95ecfd2d4a330\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9018526608029266144",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/e0534f38e87b9d51646572a1ddf95ecfd2d4a330",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e0534f38e87b9d51646572a1ddf95ecfd2d4a330",
"gitiles_ref:refs/heads/master"
]
}
[01:30:26.818] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615426226155091",
"id": "8853096545441201264",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e0534f38e87b9d51646572a1ddf95ecfd2d4a330\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615426226720280",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/181286",
"builder:Linux Builder (dbg)",
"buildset:commit/git/e0534f38e87b9d51646572a1ddf95ecfd2d4a330",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e0534f38e87b9d51646572a1ddf95ecfd2d4a330",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018526608029266144",
"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/8853096545441201264/+/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": "1615426226720389",
"url": "https://ci.chromium.org/b/8853096545441201264",
"utcnow_ts": "1615426226812211"
}
}
[01:30:26.818] Task URL: https://ci.chromium.org/b/8853096545441201264
[01:30:26.818] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:2:0) after 1m0s
[01:30:30.225] Received PubSub notification, asking Buildbucket for the build status
[01:30:30.300] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:26.875] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:2:0)
[01:31:26.875] Timer tick, asking Buildbucket for the build status
[01:31:26.950] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:26.950] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:4:0) after 1m0s
[01:32:27.001] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:4:0)
[01:32:27.001] Timer tick, asking Buildbucket for the build status
[01:32:27.068] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:27.068] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:5:0) after 1m0s
[01:33:27.134] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:5:0)
[01:33:27.134] Timer tick, asking Buildbucket for the build status
[01:33:27.212] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:27.212] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:6:0) after 1m0s
[01:34:27.270] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:6:0)
[01:34:27.270] Timer tick, asking Buildbucket for the build status
[01:34:27.429] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:27.429] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:7:0) after 1m0s
[01:35:27.456] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:7:0)
[01:35:27.456] Timer tick, asking Buildbucket for the build status
[01:35:27.589] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:27.589] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:8:0) after 1m0s
[01:36:27.642] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:8:0)
[01:36:27.642] Timer tick, asking Buildbucket for the build status
[01:36:28.039] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:28.039] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:9:0) after 1m0s
[01:37:28.195] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:9:0)
[01:37:28.195] Timer tick, asking Buildbucket for the build status
[01:37:28.275] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:28.275] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:10:0) after 1m0s
[01:38:28.381] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:10:0)
[01:38:28.381] Timer tick, asking Buildbucket for the build status
[01:38:28.560] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:28.560] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:11:0) after 1m0s
[01:39:28.566] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:11:0)
[01:39:28.566] Timer tick, asking Buildbucket for the build status
[01:39:28.833] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:28.833] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:12:0) after 1m0s
[01:40:28.933] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:12:0)
[01:40:28.933] Timer tick, asking Buildbucket for the build status
[01:40:29.038] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:29.038] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:13:0) after 1m0s
[01:41:29.143] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:13:0)
[01:41:29.143] Timer tick, asking Buildbucket for the build status
[01:41:29.405] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:29.405] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:14:0) after 1m0s
[01:42:29.439] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:14:0)
[01:42:29.439] Timer tick, asking Buildbucket for the build status
[01:42:29.518] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:29.518] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:15:0) after 1m0s
[01:43:29.656] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:15:0)
[01:43:29.656] Timer tick, asking Buildbucket for the build status
[01:43:29.733] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:29.733] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:16:0) after 1m0s
[01:44:29.820] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:16:0)
[01:44:29.820] Timer tick, asking Buildbucket for the build status
[01:44:29.888] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:29.888] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:17:0) after 1m0s
[01:45:29.938] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:17:0)
[01:45:29.938] Timer tick, asking Buildbucket for the build status
[01:45:30.031] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:30.031] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:18:0) after 1m0s
[01:46:30.125] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:18:0)
[01:46:30.125] Timer tick, asking Buildbucket for the build status
[01:46:30.343] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:30.343] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:19:0) after 1m0s
[01:47:30.425] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:19:0)
[01:47:30.425] Timer tick, asking Buildbucket for the build status
[01:47:30.554] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:30.554] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:20:0) after 1m0s
[01:48:30.553] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:20:0)
[01:48:30.553] Timer tick, asking Buildbucket for the build status
[01:48:30.638] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:30.638] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:21:0) after 1m0s
[01:49:30.667] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:21:0)
[01:49:30.667] Timer tick, asking Buildbucket for the build status
[01:49:30.733] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:30.733] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:22:0) after 1m0s
[01:50:30.754] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:22:0)
[01:50:30.754] Timer tick, asking Buildbucket for the build status
[01:50:30.848] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:30.848] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:23:0) after 1m0s
[01:51:30.844] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:23:0)
[01:51:30.844] Timer tick, asking Buildbucket for the build status
[01:51:30.920] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:30.921] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:24:0) after 1m0s
[01:52:30.943] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:24:0)
[01:52:30.943] Timer tick, asking Buildbucket for the build status
[01:52:31.004] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:31.004] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:25:0) after 1m0s
[01:53:31.070] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:25:0)
[01:53:31.070] Timer tick, asking Buildbucket for the build status
[01:53:31.167] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:31.167] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:26:0) after 1m0s
[01:54:31.334] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:26:0)
[01:54:31.334] Timer tick, asking Buildbucket for the build status
[01:54:31.495] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:31.495] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:27:0) after 1m0s
[01:55:31.636] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:27:0)
[01:55:31.636] Timer tick, asking Buildbucket for the build status
[01:55:31.737] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:31.737] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:28:0) after 1m0s
[01:56:32.206] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:28:0)
[01:56:32.206] Timer tick, asking Buildbucket for the build status
[01:56:32.520] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:32.520] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:29:0) after 1m0s
[01:57:32.666] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:29:0)
[01:57:32.666] Timer tick, asking Buildbucket for the build status
[01:57:33.049] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:33.049] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:30:0) after 1m0s
[01:58:33.020] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:30:0)
[01:58:33.020] Timer tick, asking Buildbucket for the build status
[01:58:33.137] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:33.137] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:31:0) after 1m0s
[01:59:33.189] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:31:0)
[01:59:33.189] Timer tick, asking Buildbucket for the build status
[01:59:33.344] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:33.344] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:32:0) after 1m0s
[02:00:33.390] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:32:0)
[02:00:33.390] Timer tick, asking Buildbucket for the build status
[02:00:33.512] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:33.512] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:33:0) after 1m0s
[02:01:33.577] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:33:0)
[02:01:33.577] Timer tick, asking Buildbucket for the build status
[02:01:33.711] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:33.711] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:34:0) after 1m0s
[02:02:33.720] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:34:0)
[02:02:33.720] Timer tick, asking Buildbucket for the build status
[02:02:33.789] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:33.789] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:35:0) after 1m0s
[02:03:33.827] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:35:0)
[02:03:33.827] Timer tick, asking Buildbucket for the build status
[02:03:33.956] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:33.956] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:36:0) after 1m0s
[02:04:33.974] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:36:0)
[02:04:33.974] Timer tick, asking Buildbucket for the build status
[02:04:34.047] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:34.047] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:37:0) after 1m0s
[02:05:34.058] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:37:0)
[02:05:34.058] Timer tick, asking Buildbucket for the build status
[02:05:34.218] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:34.218] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:38:0) after 1m0s
[02:06:34.237] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:38:0)
[02:06:34.237] Timer tick, asking Buildbucket for the build status
[02:06:34.839] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:34.839] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:39:0) after 1m0s
[02:07:34.865] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:39:0)
[02:07:34.865] Timer tick, asking Buildbucket for the build status
[02:07:34.954] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:34.954] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:40:0) after 1m0s
[02:08:34.962] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:40:0)
[02:08:34.962] Timer tick, asking Buildbucket for the build status
[02:08:35.147] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:35.147] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:41:0) after 1m0s
[02:09:35.190] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:41:0)
[02:09:35.190] Timer tick, asking Buildbucket for the build status
[02:09:35.248] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:35.248] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:42:0) after 1m0s
[02:10:35.286] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:42:0)
[02:10:35.286] Timer tick, asking Buildbucket for the build status
[02:10:35.447] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:35.447] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:43:0) after 1m0s
[02:11:35.484] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:43:0)
[02:11:35.484] Timer tick, asking Buildbucket for the build status
[02:11:35.558] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:35.558] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:44:0) after 1m0s
[02:12:35.728] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:44:0)
[02:12:35.728] Timer tick, asking Buildbucket for the build status
[02:12:35.799] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:35.799] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:45:0) after 1m0s
[02:13:35.882] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:45:0)
[02:13:35.882] Timer tick, asking Buildbucket for the build status
[02:13:36.114] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:36.114] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:46:0) after 1m0s
[02:14:36.277] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:46:0)
[02:14:36.277] Timer tick, asking Buildbucket for the build status
[02:14:36.357] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:36.357] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:47:0) after 1m0s
[02:15:36.387] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:47:0)
[02:15:36.387] Timer tick, asking Buildbucket for the build status
[02:15:36.498] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:36.498] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:48:0) after 1m0s
[02:16:36.597] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:48:0)
[02:16:36.597] Timer tick, asking Buildbucket for the build status
[02:16:36.799] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:36.799] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:49:0) after 1m0s
[02:17:36.934] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:49:0)
[02:17:36.934] Timer tick, asking Buildbucket for the build status
[02:17:37.146] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:37.146] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:50:0) after 1m0s
[02:18:37.157] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:50:0)
[02:18:37.157] Timer tick, asking Buildbucket for the build status
[02:18:37.216] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:37.216] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:51:0) after 1m0s
[02:19:37.241] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:51:0)
[02:19:37.241] Timer tick, asking Buildbucket for the build status
[02:19:37.341] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:37.341] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:52:0) after 1m0s
[02:20:37.469] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:52:0)
[02:20:37.469] Timer tick, asking Buildbucket for the build status
[02:20:37.547] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:37.547] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:53:0) after 1m0s
[02:21:37.665] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:53:0)
[02:21:37.665] Timer tick, asking Buildbucket for the build status
[02:21:37.728] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:37.728] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:54:0) after 1m0s
[02:22:37.741] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:54:0)
[02:22:37.741] Timer tick, asking Buildbucket for the build status
[02:22:37.810] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:37.810] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:55:0) after 1m0s
[02:23:37.903] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:55:0)
[02:23:37.903] Timer tick, asking Buildbucket for the build status
[02:23:37.970] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:37.970] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:56:0) after 1m0s
[02:24:38.113] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:56:0)
[02:24:38.113] Timer tick, asking Buildbucket for the build status
[02:24:38.173] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:38.173] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:57:0) after 1m0s
[02:25:38.346] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:57:0)
[02:25:38.346] Timer tick, asking Buildbucket for the build status
[02:25:38.562] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:38.562] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:58:0) after 1m0s
[02:26:38.666] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:58:0)
[02:26:38.666] Timer tick, asking Buildbucket for the build status
[02:26:38.804] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:38.804] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:59:0) after 1m0s
[02:27:38.855] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:59:0)
[02:27:38.855] Timer tick, asking Buildbucket for the build status
[02:27:38.943] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:38.943] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:60:0) after 1m0s
[02:28:39.092] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:60:0)
[02:28:39.092] Timer tick, asking Buildbucket for the build status
[02:28:39.182] Build 8853096545441201264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:39.182] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018526608029266144:61:0) after 1m0s
[02:29:31.927] Received PubSub notification, asking Buildbucket for the build status
[02:29:32.005] Build 8853096545441201264: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:29:32.005] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615429770829453",
"created_by": "project:chromium",
"created_ts": "1615426226155091",
"id": "8853096545441201264",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e0534f38e87b9d51646572a1ddf95ecfd2d4a330\"}}",
"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\": \"e0534f38e87b9d51646572a1ddf95ecfd2d4a330\", \"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-central1-b-8-120-qgyz\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.linux\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 181286, \"got_angle_revision\": \"331640e5c19e63209de0307ae37dc1ec69a1f4e0\", \"got_dawn_revision\": \"6d4617a2d28d8d3d4aa946abdbabb368d025b6b7\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"e0534f38e87b9d51646572a1ddf95ecfd2d4a330\", \"got_revision_cp\": \"refs/heads/master@{#861798}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a2fe8e1b2111e69d8846991f9d9bcc5c49505d1c\", \"got_v8_revision\": \"380d3d23acddd8b6eb5521fbf5cf30b9ff8ac301\", \"got_v8_revision_cp\": \"refs/heads/9.1.95@{#1}\", \"got_webrtc_revision\": \"92d12707e00837a978485f731d72ef30cf19d2a2\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33402}\", \"is_cached\": true, \"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\": \"e0534f38e87b9d51646572a1ddf95ecfd2d4a330\", \"swarm_hashes_refs/heads/master(at){#861798}_without_patch\": {\"absl_hardening_tests\": \"edf06d607f22a2d982fe62cab4d32e25a64b8ea6\", \"accessibility_unittests\": \"ca142b0a25700b006c635f659ea67714103136b3\", \"angle_unittests\": \"28385342c988ed88d722b81e4eb64a0d9375abcd\", \"app_shell_unittests\": \"76fe517c3e3faa1ac59cf65d0703b4f1ffdc529a\", \"aura_unittests\": \"99da5ada3ccf51af95634dd7265a9466e384fb00\", \"base_unittests\": \"1605cbdf2f6179c46bf7f9d212d72a6a3899fe51\", \"base_util_unittests\": \"ea5c3a4025a17c26de7eb861840a250940444258\", \"blink_common_unittests\": \"d4c282befb79c0077756649236d438895fc7c76a\", \"blink_fuzzer_unittests\": \"445672712919767a9f2a2d92e4d1266a840ba1bc\", \"blink_heap_unittests\": \"dc072b1716ffd33f69e1a3d47b795915e38a61d9\", \"blink_platform_unittests\": \"5a280fd906bdb9f0fcd4853761ca3355bb76500d\", \"blink_python_tests\": \"ec4f5c0da03b26df947ccd62013c5fd0f640f5d5\", \"blink_unittests\": \"1720c040e81efc30a84fb4125dd6e1843daa3915\", \"blink_web_tests\": \"edfbf9bf85266af4ba9f181d6d2c861930c87649\", \"boringssl_crypto_tests\": \"b3b69ff79895de3a0960d6aecf5239cade723628\", \"boringssl_ssl_tests\": \"ab712429455909d8b6b78ade69d83679cb254077\", \"browser_tests\": \"a795ef014195f33e9f9fcb232f0027fa47b20a8d\", \"capture_unittests\": \"11655de373f2d1f6d4355dc7ee740ef2e286eca4\", \"cast_unittests\": \"4d0c174881442f0c92ae303ce4e23821a3d01a85\", \"cc_unittests\": \"b6a22478b2e9cdfbdc015ffb062732fa8f750ab8\", \"chrome_app_unittests\": \"ca5071a5a77d3120c5caf663a5ceceeb7004c57a\", \"chromedriver_unittests\": \"e1148be2c7585fa53625626d57f005d8e265fdf5\", \"color_unittests\": \"d0bc899b18e790d1048908ece57e9b90fbec8af6\", \"components_browsertests\": \"fe273752e56a36a5303d7c0b11e961dc8e66c274\", \"components_unittests\": \"3f7e55202be9cac675b65fd85007e11361fb6ff3\", \"compositor_unittests\": \"18c0dd0a5c8fcf172dbaa9460db800fde5b33596\", \"content_browsertests\": \"54d04210140c43ed622705086fb9d6b6c59f752e\", \"content_shell_crash_test\": \"6ea8ad2f01faab050b0c8cf9ee024b10e23d8143\", \"content_unittests\": \"f3107bef4517365402c79fe0c1ff1f260e9d4961\", \"crashpad_tests\": \"81011ec44ff8df7e21e7ee0d746a3e156f5fb171\", \"cronet_tests\": \"d9637a24bc92a60c8b6997a35b3bcf4f938df723\", \"cronet_unittests\": \"9a388529e19dfdb4c0c13a8e88155496a725abb8\", \"crypto_unittests\": \"d2595fbec88ed3bad0bc1bb0d74060d21418657e\", \"dbus_unittests\": \"5d0349448d1f08dc3cd7ff19d5865bb1f4c24ebb\", \"device_unittests\": \"071f10e5417a6aef42078a142938b497f4cb599f\", \"display_unittests\": \"15700d1e8faec34df46c352e2021c864f55094b9\", \"events_unittests\": \"81d40ccd983e281f63ea6157015e46a1669fd6e6\", \"extensions_browsertests\": \"3e819032df0749976e415e450557ac5036b43d8e\", \"extensions_unittests\": \"624aeb78c05bd7acd8a0286f6bcca17aff76e24d\", \"filesystem_service_unittests\": \"986e685ec164c173f3727c3a5d48c0911c9d685e\", \"flatbuffers_unittests\": \"917c1926f9a03d6ef30fc9665ef3a1d956152ddf\", \"gcm_unit_tests\": \"0ea7808df305f0d41d16d3bdd2c145006c8c80d3\", \"gfx_unittests\": \"352ac29761d71ee3632f99349959e73fce16c37f\", \"gin_unittests\": \"b7ee54d4b77256e85199b6049cf5bff068ec7450\", \"google_apis_unittests\": \"4e301505fe2d6f7f2b0c15db3a356013e5ebcaf5\", \"gpu_unittests\": \"107394137e2500825a706323bf80750d24ad3e35\", \"grit_python_unittests\": \"2629172029a5ce27ead3b3791972502f1e7f17c8\", \"gwp_asan_unittests\": \"01ee4b6593b17e7b5669ca79f496d61e5881a7eb\", \"headless_browsertests\": \"d6c60ca17f9c5e626db5b503ff42a68fcf9f7004\", \"headless_unittests\": \"8ed65acd87f8ea1023bb82dd59cce923ef0bcd68\", \"interactive_ui_tests\": \"c92ba1463e7ab131ef5e3afada7eb6350b2dcf92\", \"ipc_tests\": \"b5d1a8eb7c3c51b59c89b7f4bd8e46bd0f2d3cb9\", \"jingle_unittests\": \"843cea3e8196938b37f79aae08eb84447cad9e38\", \"latency_unittests\": \"5e02c4412b4760e4760666a8967eef12e8ab4b0e\", \"libjingle_xmpp_unittests\": \"b22aaea32d899ba902cfa0b1ace11797ab22c759\", \"liburlpattern_unittests\": \"63239e2a1c011b27197b3d0d8d514d9ab7ffeb46\", \"media_blink_unittests\": \"58e180d510280c66482231770e2ebf8509850f2b\", \"media_unittests\": \"0ca711010e57e86a449c3aa334eb04769de0caf4\", \"message_center_unittests\": \"3edc85a2dc15ecfc807010b7c5b7dca9e0cc3cb7\", \"metrics_python_tests\": \"f9ad1586f82f6ed6cb891778ea5f6d48ca421adb\", \"midi_unittests\": \"9a3c114a6b1af12ea0b008ee453f0e7536f8dfd3\", \"mojo_core_unittests\": \"c5f1536f75b09a8b40fa4a152760d2d736d29e92\", \"mojo_unittests\": \"15c60c5b5cddf1bdd7b181517cec24d293b51e53\", \"nacl_helper_nonsfi_unittests\": \"63ff8526966c399e904d8287e10ad1ea76c12765\", \"nacl_loader_unittests\": \"6f010813f0c68f093622a4ac9f7ed1b7ef868195\", \"native_theme_unittests\": \"5d2a27eabef2d5b6282d32075673fd1736297139\", \"net_unittests\": \"af43bd42921c6d0427c052f07d3af6a99787b3c3\", \"openscreen_unittests\": \"df8b0825c23dd66a7ebd548398d482586f9688cf\", \"pdf_unittests\": \"4f1dd982243f662f1dd297d6d1ee482ed43a8710\", \"perfetto_unittests\": \"339d2a2c389351f452ff8bb0a430971225ec0178\", \"ppapi_unittests\": \"66932ac029a4ec4900e1f998cf6e277cae3a3db8\", \"printing_unittests\": \"1be8c65d4f980135dbc7c585bcc0923277fd6461\", \"remoting_unittests\": \"a48a89617bf562c9e0bf4298b4f821bb91f36250\", \"sandbox_linux_unittests\": \"7a2375a57100ac53ca7a4a9ed0ad51244122a472\", \"service_manager_unittests\": \"dfee69160d786da6ec820aea030699fcd5bfd64f\", \"services_unittests\": \"7c36f12c16b64dd6910efe8bfc5a0fb2ad646481\", \"shell_dialogs_unittests\": \"8aacbf3b4d8e9bab31265572baab2acc45ac1835\", \"skia_unittests\": \"ba9fe734f6acf2dbcd1030bda0478d2e5e54d288\", \"snapshot_unittests\": \"cc1d25cae1ad8951c8f338a24508cd85851945b1\", \"sql_unittests\": \"a7ed6f3d63332b98af480263fd9164a5cdf4898a\", \"storage_unittests\": \"c15e07b1f3e802cc92c1856b5fb1da7b525026ff\", \"sync_integration_tests\": \"c14aa21ea2c490d03c2adeedc5a9630428a3e66d\", \"telemetry_gpu_unittests\": \"6ebabf449ac3c460fecfbe9b2dd756b85c0046cd\", \"telemetry_perf_unittests\": \"48f031ac6390656d29fc9a14bed3f1e2f525788a\", \"telemetry_unittests\": \"badfd8ceae473853a61d495d2766709140dc16b6\", \"traffic_annotation_auditor_unittests\": \"7ef7c0d3093059f967f28cc8c245993a277ab933\", \"ui_base_unittests\": \"74f64afbb21dd47e3a5656d12b12f09c928b870d\", \"ui_touch_selection_unittests\": \"07a695eb784e48f0d51d3d429869ccd68df98ceb\", \"unit_tests\": \"0cddbf6d60657c49898d48dee351f62a8d2bf9bd\", \"url_unittests\": \"22d458ef86bf2db054ca646fd4507af39b7d2f27\", \"views_perftests\": \"0018ce7ce8a8b26a51af3f583c3f60c3f39322cd\", \"views_unittests\": \"2d02a445f869e76708b86a16a235f11d5b419c23\", \"viz_unittests\": \"cd6f068bfa9df38d849dfb36bed4c789576ae1a1\", \"vr_common_unittests\": \"e278dfd01bda94c6b1d1195de3dbd035d524a4dc\", \"vr_pixeltests\": \"1c992555c286fe4e5a8cf44c4cfd71faa4b1d54f\", \"webdriver_wpt_tests\": \"48c28e26829dbfa4ab18167541ff24810943843e\", \"weblayer_browsertests\": \"b429c9569d6524541474b8ddd56ff376a6a0ca2c\", \"weblayer_unittests\": \"f0a731cf888ad69426400a4cd62773c37e7a4709\", \"wm_unittests\": \"639439d1a6cc629b451e8a2d9627ef9e122a9b6c\", \"wtf_unittests\": \"d405092b10282cf9fb6c51508982e3d95d01925a\", \"x11_unittests\": \"dfeb293baa081fd7573644b9634b70abd0a29891\", \"xr_browser_tests\": \"e55d74cc9fc7d2dd92bc8fd13b7433d8c3148489\", \"zlib_unittests\": \"f2dd5adafa0e9d9a7380018c8d4cb5b7ac3b8b85\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_12fe258969bd458c0c491cc8ec8a110de21b19ba6d4540c003334ed929b828ba_v2\", \"builder_13c9e4757ca6a1772742c665222564667630a6453df92e57dfb1ace791cbcc32_v2\", \"builder_1989594bdf03467d4cca251563547d36d290a477ee0cdac91b5f17239ddd8c8b_v2\", \"builder_df0918bfe72974da8c562c0af785205b640d6f631f017829e032ab97ab701012_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-central1-b-8-120-qgyz\"], \"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\": [\"5754-8c4f760\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615426229125861",
"status": "COMPLETED",
"status_changed_ts": "1615429771727706",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/181286",
"builder:Linux Builder (dbg)",
"buildset:commit/git/e0534f38e87b9d51646572a1ddf95ecfd2d4a330",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e0534f38e87b9d51646572a1ddf95ecfd2d4a330",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018526608029266144",
"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/8853096545441201264/+/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:5237b99366eff910",
"user_agent:luci-scheduler"
],
"updated_ts": "1615429771732004",
"url": "https://ci.chromium.org/b/8853096545441201264",
"utcnow_ts": "1615429771996955"
}
}
[02:29:32.006] Invocation finished in 59m7.70140611s with status SUCCEEDED