[01:59:08.442] New invocation is queued and will start shortly
[01:59:09.688] Starting the invocation (attempt 1)
[01:59:09.730] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:59:09.730] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:59:09.730] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018343606224469776",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"16b74d21a0315da2e793b258050f8b007e75f243\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9018343606224469776",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/16b74d21a0315da2e793b258050f8b007e75f243",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/16b74d21a0315da2e793b258050f8b007e75f243",
"gitiles_ref:refs/heads/master"
]
}
[01:59:10.619] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615600749866261",
"id": "8852913544066439312",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"16b74d21a0315da2e793b258050f8b007e75f243\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615600750382512",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/181349",
"builder:Linux Builder (dbg)",
"buildset:commit/git/16b74d21a0315da2e793b258050f8b007e75f243",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/16b74d21a0315da2e793b258050f8b007e75f243",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018343606224469776",
"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/8852913544066439312/+/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": "1615600750382637",
"url": "https://ci.chromium.org/b/8852913544066439312",
"utcnow_ts": "1615600750613504"
}
}
[01:59:10.619] Task URL: https://ci.chromium.org/b/8852913544066439312
[01:59:10.619] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:2:0) after 1m0s
[01:59:13.293] Received PubSub notification, asking Buildbucket for the build status
[01:59:13.353] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:10.641] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:2:0)
[02:00:10.641] Timer tick, asking Buildbucket for the build status
[02:00:10.723] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:10.723] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:4:0) after 1m0s
[02:01:10.757] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:4:0)
[02:01:10.757] Timer tick, asking Buildbucket for the build status
[02:01:10.933] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:10.933] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:5:0) after 1m0s
[02:02:10.963] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:5:0)
[02:02:10.963] Timer tick, asking Buildbucket for the build status
[02:02:11.031] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:11.031] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:6:0) after 1m0s
[02:03:11.045] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:6:0)
[02:03:11.045] Timer tick, asking Buildbucket for the build status
[02:03:11.127] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:11.127] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:7:0) after 1m0s
[02:04:11.144] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:7:0)
[02:04:11.144] Timer tick, asking Buildbucket for the build status
[02:04:11.221] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:11.221] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:8:0) after 1m0s
[02:05:11.239] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:8:0)
[02:05:11.239] Timer tick, asking Buildbucket for the build status
[02:05:11.325] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:11.325] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:9:0) after 1m0s
[02:06:11.332] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:9:0)
[02:06:11.332] Timer tick, asking Buildbucket for the build status
[02:06:11.407] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:11.407] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:10:0) after 1m0s
[02:07:11.418] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:10:0)
[02:07:11.418] Timer tick, asking Buildbucket for the build status
[02:07:12.038] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:12.038] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:11:0) after 1m0s
[02:08:12.059] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:11:0)
[02:08:12.059] Timer tick, asking Buildbucket for the build status
[02:08:12.130] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:12.130] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:12:0) after 1m0s
[02:09:12.172] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:12:0)
[02:09:12.172] Timer tick, asking Buildbucket for the build status
[02:09:12.263] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:12.263] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:13:0) after 1m0s
[02:10:12.394] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:13:0)
[02:10:12.394] Timer tick, asking Buildbucket for the build status
[02:10:12.561] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:12.561] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:14:0) after 1m0s
[02:11:12.557] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:14:0)
[02:11:12.557] Timer tick, asking Buildbucket for the build status
[02:11:12.750] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:12.750] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:15:0) after 1m0s
[02:12:12.882] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:15:0)
[02:12:12.882] Timer tick, asking Buildbucket for the build status
[02:12:13.128] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:13.128] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:16:0) after 1m0s
[02:13:13.306] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:16:0)
[02:13:13.306] Timer tick, asking Buildbucket for the build status
[02:13:13.516] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:13.516] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:17:0) after 1m0s
[02:14:13.703] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:17:0)
[02:14:13.703] Timer tick, asking Buildbucket for the build status
[02:14:13.791] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:13.791] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:18:0) after 1m0s
[02:15:14.142] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:18:0)
[02:15:14.142] Timer tick, asking Buildbucket for the build status
[02:15:14.319] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:14.319] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:19:0) after 1m0s
[02:16:14.639] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:19:0)
[02:16:14.639] Timer tick, asking Buildbucket for the build status
[02:16:14.754] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:14.754] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:20:0) after 1m0s
[02:17:14.900] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:20:0)
[02:17:14.900] Timer tick, asking Buildbucket for the build status
[02:17:15.006] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:15.006] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:21:0) after 1m0s
[02:18:15.030] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:21:0)
[02:18:15.030] Timer tick, asking Buildbucket for the build status
[02:18:15.101] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:15.101] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:22:0) after 1m0s
[02:19:15.085] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:22:0)
[02:19:15.085] Timer tick, asking Buildbucket for the build status
[02:19:15.308] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:15.308] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:23:0) after 1m0s
[02:20:15.357] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:23:0)
[02:20:15.357] Timer tick, asking Buildbucket for the build status
[02:20:15.547] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:15.547] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:24:0) after 1m0s
[02:21:15.619] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:24:0)
[02:21:15.619] Timer tick, asking Buildbucket for the build status
[02:21:16.131] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:16.131] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:25:0) after 1m0s
[02:22:16.181] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:25:0)
[02:22:16.181] Timer tick, asking Buildbucket for the build status
[02:22:16.248] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:16.248] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:26:0) after 1m0s
[02:23:16.290] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:26:0)
[02:23:16.290] Timer tick, asking Buildbucket for the build status
[02:23:16.432] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:16.432] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:27:0) after 1m0s
[02:24:16.551] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:27:0)
[02:24:16.551] Timer tick, asking Buildbucket for the build status
[02:24:16.781] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:16.781] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:28:0) after 1m0s
[02:25:16.818] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:28:0)
[02:25:16.818] Timer tick, asking Buildbucket for the build status
[02:25:17.046] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:17.046] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:29:0) after 1m0s
[02:26:17.131] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:29:0)
[02:26:17.131] Timer tick, asking Buildbucket for the build status
[02:26:17.623] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:17.623] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:30:0) after 1m0s
[02:27:17.704] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:30:0)
[02:27:17.704] Timer tick, asking Buildbucket for the build status
[02:27:17.765] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:17.765] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:31:0) after 1m0s
[02:28:17.791] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:31:0)
[02:28:17.791] Timer tick, asking Buildbucket for the build status
[02:28:17.870] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:17.870] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:32:0) after 1m0s
[02:29:18.012] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:32:0)
[02:29:18.012] Timer tick, asking Buildbucket for the build status
[02:29:18.111] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:18.111] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:33:0) after 1m0s
[02:30:18.192] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:33:0)
[02:30:18.192] Timer tick, asking Buildbucket for the build status
[02:30:18.279] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:18.279] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:34:0) after 1m0s
[02:31:18.308] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:34:0)
[02:31:18.308] Timer tick, asking Buildbucket for the build status
[02:31:18.368] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:18.368] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:35:0) after 1m0s
[02:32:18.408] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:35:0)
[02:32:18.408] Timer tick, asking Buildbucket for the build status
[02:32:18.559] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:18.559] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:36:0) after 1m0s
[02:33:18.589] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:36:0)
[02:33:18.589] Timer tick, asking Buildbucket for the build status
[02:33:19.814] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:19.814] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:37:0) after 1m0s
[02:34:19.868] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:37:0)
[02:34:19.868] Timer tick, asking Buildbucket for the build status
[02:34:19.953] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:19.953] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:38:0) after 1m0s
[02:35:19.969] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:38:0)
[02:35:19.969] Timer tick, asking Buildbucket for the build status
[02:35:20.041] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:20.041] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:39:0) after 1m0s
[02:36:20.063] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:39:0)
[02:36:20.063] Timer tick, asking Buildbucket for the build status
[02:36:20.224] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:20.224] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:40:0) after 1m0s
[02:37:20.245] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:40:0)
[02:37:20.245] Timer tick, asking Buildbucket for the build status
[02:37:20.319] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:20.319] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:41:0) after 1m0s
[02:38:20.369] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:41:0)
[02:38:20.369] Timer tick, asking Buildbucket for the build status
[02:38:20.437] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:20.437] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:42:0) after 1m0s
[02:39:20.461] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:42:0)
[02:39:20.461] Timer tick, asking Buildbucket for the build status
[02:39:20.542] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:20.542] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:43:0) after 1m0s
[02:40:20.608] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:43:0)
[02:40:20.608] Timer tick, asking Buildbucket for the build status
[02:40:20.678] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:20.678] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:44:0) after 1m0s
[02:41:20.696] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:44:0)
[02:41:20.696] Timer tick, asking Buildbucket for the build status
[02:41:20.947] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:20.947] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:45:0) after 1m0s
[02:42:20.999] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:45:0)
[02:42:20.999] Timer tick, asking Buildbucket for the build status
[02:42:21.211] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:21.211] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:46:0) after 1m0s
[02:43:21.228] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:46:0)
[02:43:21.228] Timer tick, asking Buildbucket for the build status
[02:43:21.299] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:21.299] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:47:0) after 1m0s
[02:44:21.335] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:47:0)
[02:44:21.335] Timer tick, asking Buildbucket for the build status
[02:44:21.499] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:21.499] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:48:0) after 1m0s
[02:45:21.560] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:48:0)
[02:45:21.560] Timer tick, asking Buildbucket for the build status
[02:45:21.645] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:21.645] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:49:0) after 1m0s
[02:46:21.690] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:49:0)
[02:46:21.690] Timer tick, asking Buildbucket for the build status
[02:46:21.761] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:21.761] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:50:0) after 1m0s
[02:47:21.808] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:50:0)
[02:47:21.808] Timer tick, asking Buildbucket for the build status
[02:47:22.019] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:22.019] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:51:0) after 1m0s
[02:48:22.116] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:51:0)
[02:48:22.116] Timer tick, asking Buildbucket for the build status
[02:48:22.349] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:22.349] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:52:0) after 1m0s
[02:49:22.351] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:52:0)
[02:49:22.351] Timer tick, asking Buildbucket for the build status
[02:49:22.440] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:22.440] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:53:0) after 1m0s
[02:50:22.485] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:53:0)
[02:50:22.485] Timer tick, asking Buildbucket for the build status
[02:50:22.706] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:22.706] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:54:0) after 1m0s
[02:51:22.723] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:54:0)
[02:51:22.723] Timer tick, asking Buildbucket for the build status
[02:51:22.832] Build 8852913544066439312: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:22.832] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9018343606224469776:55:0) after 1m0s
[02:52:18.094] Received PubSub notification, asking Buildbucket for the build status
[02:52:18.187] Build 8852913544066439312: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:52:18.187] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615603937218321",
"created_by": "project:chromium",
"created_ts": "1615600749866261",
"id": "8852913544066439312",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"16b74d21a0315da2e793b258050f8b007e75f243\"}}",
"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\": \"16b74d21a0315da2e793b258050f8b007e75f243\", \"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-215-vhnk\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.linux\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 181349, \"got_angle_revision\": \"41a2672f43fb125e83b4942133e487995da9bf7c\", \"got_dawn_revision\": \"c53ea046a603d5085268584f16bfdf61586f4c4c\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"16b74d21a0315da2e793b258050f8b007e75f243\", \"got_revision_cp\": \"refs/heads/master@{#862632}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"314aa755ed3af01dc42d77587752a32a883972ed\", \"got_v8_revision\": \"9d12b666c3e113f5cd61e2b2eb384aae242ac840\", \"got_v8_revision_cp\": \"refs/heads/9.1.105@{#1}\", \"got_webrtc_revision\": \"86473404362e38131203537b8a8c8ec7c1abc31a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33444}\", \"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\": \"16b74d21a0315da2e793b258050f8b007e75f243\", \"swarm_hashes_refs/heads/master(at){#862632}_without_patch\": {\"absl_hardening_tests\": \"81e4df814189225079071f03b3c59ebe7be72713\", \"accessibility_unittests\": \"7e0a47b2f516bc04f610f92ceea00b20269f1685\", \"angle_unittests\": \"e29329c8f731fe50899e11840a416651e2592769\", \"app_shell_unittests\": \"5519fc68571cd0b1ad73c91d0979c95e39179d4b\", \"aura_unittests\": \"b07d5e16863d6852d2e7bbf6e4a810a2a1c101ef\", \"base_unittests\": \"0508fdeb341abe9bac4830fd48ad8e48342dd61a\", \"base_util_unittests\": \"eaa35ecac33b0fb316501e160670bb81325f54a0\", \"blink_common_unittests\": \"e1f24b07eb573151c6195d8e2db845b7a6d03dd3\", \"blink_fuzzer_unittests\": \"b2a1619cb9ad4207a691cfed9bf0a100de180aed\", \"blink_heap_unittests\": \"37b0a02fe2438a795e20ba2132277054b4353c99\", \"blink_platform_unittests\": \"d59db4061afe7ea42c480c23dabc1d0ae94a05db\", \"blink_python_tests\": \"28fa9ec550d618962c00e6a49f06fcfc07f9f44c\", \"blink_unittests\": \"ba0da83a3ea35da8f085f2c7f01046f8b2f8ec40\", \"blink_web_tests\": \"7c741bc85327800fc9188cd9e1d853f19434c2c1\", \"boringssl_crypto_tests\": \"24e10c0e6a38ab3fa0d9db53cbfe02f3279f6a26\", \"boringssl_ssl_tests\": \"c48c4db818037ce820a640d73754e0ef6ccb1afd\", \"browser_tests\": \"4cf5e141b5ff0160940c7267c9deb556a3d06a8d\", \"capture_unittests\": \"275d0532d37c284922baa79eeef4598a946b8923\", \"cast_unittests\": \"e0e8752c7ba5e5fcd20d9c91acc3d9070fe71c78\", \"cc_unittests\": \"353d6b1eb09b2390393babb9f762cb3ac93ec11e\", \"chrome_app_unittests\": \"cecd63089bb76d7dee619e8176d325a8aa4c28d0\", \"chromedriver_unittests\": \"272db1f0044963bdbcd23bbcf959808cf718fa80\", \"color_unittests\": \"775a354345a6647f4ece8dc0adbfb7a437d55349\", \"components_browsertests\": \"79b30b47814b71a4cd8d144ab4f850c7d026ffae\", \"components_unittests\": \"c438b88da80271663f3ffec83d5adc49200055b5\", \"compositor_unittests\": \"b88cf29d492650a884c5b19f0d9f2b9e2005c034\", \"content_browsertests\": \"b354ede52f105039b932eef1020590c7f43a9193\", \"content_shell_crash_test\": \"25a2219617febd9f8dfa0bdabbd290d6d5bfae31\", \"content_unittests\": \"f8d373f0d8548101c6e398f3ac06dff2bde2698d\", \"crashpad_tests\": \"d59fef4e9d212524af14435d7350424b6a47106f\", \"cronet_tests\": \"006cc99f6b8871ba632d2c985176b74cebdac144\", \"cronet_unittests\": \"625193720b08d7f1f03f568aa38877024cc2fbfb\", \"crypto_unittests\": \"993d91d727a6b100179ddb454a9a5ac2e49df492\", \"dbus_unittests\": \"df75090992724914107e6547781c371f1caa8087\", \"device_unittests\": \"fa81378469d9fbf86fe3506e09652774565148cf\", \"display_unittests\": \"6b78e67e82aa02b099e11f6aa4e04e99357cf8cb\", \"events_unittests\": \"9c0209ce43abb69c94fd57a0a5ac5aaffcf23835\", \"extensions_browsertests\": \"ce18fc83966c371ad438994ac8cc40869e533b91\", \"extensions_unittests\": \"df3e19f50699f104bd152942975cb8c5e5567272\", \"filesystem_service_unittests\": \"d001832c05510910a72b092007e7a67b971c0dc2\", \"flatbuffers_unittests\": \"917c1926f9a03d6ef30fc9665ef3a1d956152ddf\", \"gcm_unit_tests\": \"0de85fdf2daa42d47b70e76d38f63d0b5da7bd9c\", \"gfx_unittests\": \"46dc06c2e5b4e88c98ac42fe79e1559eb58957ad\", \"gin_unittests\": \"7c322312e84b411b1956276f332243dcb99b5626\", \"google_apis_unittests\": \"2a820c51af44324a2aa5b942745815e35674e9e3\", \"gpu_unittests\": \"6f22e49db8fd55badc961af5a25a4a0772313b5a\", \"grit_python_unittests\": \"34dc6f3cc2d88a5fac848f4742c34f024387cdb5\", \"gwp_asan_unittests\": \"5588e9e49a51d8c75bdbe7299d3135cc6102dcdd\", \"headless_browsertests\": \"1cbeecf791828d860c87f99d4456f75b97e1d2a6\", \"headless_unittests\": \"0b655725a886e90c25878e54ed1afcc11b57b7fb\", \"interactive_ui_tests\": \"0a42d9c2634543c7695e2e4ad3744610959ffb7d\", \"ipc_tests\": \"3c93f63902d5ee412e62d4abd926fc2912a3c0ea\", \"jingle_unittests\": \"8c0e80b9b92f01782670054e4cfc8a32856c8b32\", \"latency_unittests\": \"84185e466af08b9f0caccb4173884a85f0343727\", \"libjingle_xmpp_unittests\": \"765c5480c9d700c78f31f3325423221ebb7c352a\", \"liburlpattern_unittests\": \"d2c98d665cbbcc9d487de2eebfbd016c32b68e78\", \"media_blink_unittests\": \"ddf73149590944606d669e2756f4b0db63e5438e\", \"media_unittests\": \"793b024ffe924c603ae066f7c846d57a2c0d5a3f\", \"message_center_unittests\": \"8c3f8aefbd4a3a5eecf5dab2ca24cc8846ee833e\", \"metrics_python_tests\": \"5e78826c9a66d18a0d00731224fd3a77eda92708\", \"midi_unittests\": \"2673ee11291c827ed2f1527a423c2bc68901e5d3\", \"mojo_core_unittests\": \"5681ed1efb409ff5382c7c29e051d3bba8a5d369\", \"mojo_unittests\": \"8c23d0e5d3c30c970acb9945151546bf2abd3d3d\", \"nacl_helper_nonsfi_unittests\": \"c4642f29e63d66932436a3571895280c27cb5b72\", \"nacl_loader_unittests\": \"b3937aedc60bb26a9bc44a8fb1d11599c383797c\", \"native_theme_unittests\": \"1fcc05989f01c31bd81e1761b044fc213310accd\", \"net_unittests\": \"e3503913a3c0f50e2bb15eea69d65d6fb2a233ed\", \"openscreen_unittests\": \"6a11de6c30f3c2e444ccd3b5042519242d275ee0\", \"pdf_unittests\": \"04805245033e3724893eec4f9010a99d8eed8a60\", \"perfetto_unittests\": \"b7e74db10ec51247f753b774cbeacd30030a4c1a\", \"ppapi_unittests\": \"45887e4bfba44de54d2a8509c1042b773804147b\", \"printing_unittests\": \"42c0d19e083677ca5447fde9d59519f59378dc1e\", \"remoting_unittests\": \"c01418d173ce495ae4501f5baf4354030d5bdca5\", \"sandbox_linux_unittests\": \"e147d12b7bd552c769e039bbec94f64f738d872d\", \"service_manager_unittests\": \"4bf9ff4f457184d9863821b02de83e4f80fd5e67\", \"services_unittests\": \"98ea4c8c95f29b2ae50f70744827934ff1ed9cc4\", \"shell_dialogs_unittests\": \"4dc32b62e6931fecc6d6f2051c9003d2d8a9d9fb\", \"skia_unittests\": \"7a14019d02377f6c298aa40f4a37e16f5658d962\", \"snapshot_unittests\": \"83415104d1c5171f31da5ecb7d4aed336a6f0e8c\", \"sql_unittests\": \"15559446722587c9bad90a49050c5d2b2d4032bb\", \"storage_unittests\": \"eeedc1aa2668375a1650a2224865a2b4070165e5\", \"sync_integration_tests\": \"3dab014d644ea7792cadc0fc4c83f497a964604e\", \"telemetry_gpu_unittests\": \"b122d28098d752fc28af44b32c978fd64162c53c\", \"telemetry_perf_unittests\": \"4e23175c0afd3573900a80266d855d3bc33a624f\", \"telemetry_unittests\": \"c7c0b38a415b19ba311c0cda514f08f006412010\", \"traffic_annotation_auditor_unittests\": \"f58675fabeace9781d93b228d65dcc1b994f4371\", \"ui_base_unittests\": \"0e56fcc637510724c3488e28016070cbd4f57446\", \"ui_touch_selection_unittests\": \"8583d9c2dec7033554d94287e3397045102fea22\", \"unit_tests\": \"2e0c74f4ee96eb9affaca3335d0d0a68b5f008c6\", \"url_unittests\": \"2ba9f58f01c602ffcf1f0ccc47e87a9c76e176ae\", \"views_perftests\": \"05e05c82718e2f7095a80fd33f96539ccedf5cf7\", \"views_unittests\": \"807d28f7fd7b690a33ff8c2508ec1f8fda09b02d\", \"viz_unittests\": \"c7f3106e16c6d76c5acb58552e58180385f692e6\", \"vr_common_unittests\": \"fd9a0cca3fc70896989edc9ce90cca5530660a1a\", \"vr_pixeltests\": \"60e4544b64dc25ada8f14bc40059c4dbf97034e4\", \"webdriver_wpt_tests\": \"5a7aee8deeb55845e271df41f6a0ff497b0cfc6c\", \"weblayer_browsertests\": \"c95304f8c820eda4608383eed4ee8acc1221e0cc\", \"weblayer_unittests\": \"828770fab1e8e0dacce4a85c5c144bc876843089\", \"wm_unittests\": \"46014ebaff67d31950a3e3378af2f494ab2c0771\", \"wtf_unittests\": \"3d5bf4d4e5fe46040c6f3d479e7275306f3aca63\", \"x11_unittests\": \"5e96997d036060953bbbd78baa07f01e5d2aec6e\", \"xr_browser_tests\": \"833ba67a1c89945c8d3742fa13de477d20736fec\", \"zlib_unittests\": \"945278843c07b66bb965162b6dec577abcee6a79\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02b94346fc981ed3c3847ac0524bcc084571ef426302b2a255781857b227612a_v2\", \"builder_4e62a2152674f2c3254d7105a557302458cce07b755aab1e21dcbd6c7ddeb832_v2\", \"builder_5f9879dd307b3e3de87d4273aaf77dfc3adf50a63c1d75c3b245bfdff463a933_v2\", \"builder_b624744545b53f30069a9e0f9d655dd80cb0688eb6f8753c5d4d1f02e2478691_v2\", \"builder_c9711ee8cb745437051a87b27c8715038746bd3b2c402df1c75730a9e112a9f8_v2\", \"builder_e21c17032b725bcf1c922976a36352546baf3f39ec148d1e6730a93c0400d8cc_v2\", \"builder_fac2959e29eeee13eb43de2791ff2fcb7aa7ab15952f0beca21f4615b55fa2a2_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-215-vhnk\"], \"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": "1615600752524346",
"status": "COMPLETED",
"status_changed_ts": "1615603937715977",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/181349",
"builder:Linux Builder (dbg)",
"buildset:commit/git/16b74d21a0315da2e793b258050f8b007e75f243",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/16b74d21a0315da2e793b258050f8b007e75f243",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018343606224469776",
"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/8852913544066439312/+/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:524220987de37810",
"user_agent:luci-scheduler"
],
"updated_ts": "1615603937742977",
"url": "https://ci.chromium.org/b/8852913544066439312",
"utcnow_ts": "1615603938175507"
}
}
[02:52:18.187] Invocation finished in 53m9.762625896s with status SUCCEEDED