[05:56:12.261] New invocation is queued and will start shortly
[05:56:13.347] Starting the invocation (attempt 1)
[05:56:13.386] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:56:13.388] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:56:13.388] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9056017029490150496",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a94bda97ace74a74b06ca87303defce4df217a2f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9056017029490150496",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/a94bda97ace74a74b06ca87303defce4df217a2f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a94bda97ace74a74b06ca87303defce4df217a2f",
"gitiles_ref:refs/heads/master"
]
}
[05:56:14.342] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1579672573617903",
"id": "8890586967405128832",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a94bda97ace74a74b06ca87303defce4df217a2f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1579672574064391",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/167673",
"builder:Linux Builder (dbg)",
"buildset:commit/git/a94bda97ace74a74b06ca87303defce4df217a2f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a94bda97ace74a74b06ca87303defce4df217a2f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9056017029490150496",
"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/8890586967405128832/+/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": "1579672574064491",
"url": "https://ci.chromium.org/b/8890586967405128832",
"utcnow_ts": "1579672574326660"
}
}
[05:56:14.342] Task URL: https://ci.chromium.org/b/8890586967405128832
[05:56:14.342] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:2:0) after 1m0s
[05:57:14.390] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:2:0)
[05:57:14.390] Timer tick, asking Buildbucket for the build status
[05:57:14.739] Build 8890586967405128832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:57:14.739] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:3:0) after 1m0s
[05:58:14.764] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:3:0)
[05:58:14.764] Timer tick, asking Buildbucket for the build status
[05:58:14.888] Build 8890586967405128832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:58:14.888] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:4:0) after 1m0s
[05:59:14.935] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:4:0)
[05:59:14.935] Timer tick, asking Buildbucket for the build status
[05:59:15.112] Build 8890586967405128832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:59:15.112] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:5:0) after 1m0s
[06:00:15.159] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:5:0)
[06:00:15.159] Timer tick, asking Buildbucket for the build status
[06:00:15.621] Build 8890586967405128832: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:00:15.621] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:6:0) after 1m0s
[06:01:03.481] Received PubSub notification, asking Buildbucket for the build status
[06:01:03.590] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:15.744] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:6:0)
[06:01:15.744] Timer tick, asking Buildbucket for the build status
[06:01:16.000] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:16.000] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:8:0) after 1m0s
[06:02:16.037] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:8:0)
[06:02:16.037] Timer tick, asking Buildbucket for the build status
[06:02:16.774] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:16.774] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:9:0) after 1m0s
[06:03:16.802] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:9:0)
[06:03:16.802] Timer tick, asking Buildbucket for the build status
[06:03:16.961] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:16.961] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:10:0) after 1m0s
[06:04:16.992] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:10:0)
[06:04:16.992] Timer tick, asking Buildbucket for the build status
[06:04:17.701] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:17.701] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:11:0) after 1m0s
[06:05:17.722] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:11:0)
[06:05:17.722] Timer tick, asking Buildbucket for the build status
[06:05:17.894] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:17.894] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:12:0) after 1m0s
[06:06:17.911] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:12:0)
[06:06:17.911] Timer tick, asking Buildbucket for the build status
[06:06:18.225] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:18.225] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:13:0) after 1m0s
[06:07:18.321] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:13:0)
[06:07:18.321] Timer tick, asking Buildbucket for the build status
[06:07:18.624] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:18.624] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:14:0) after 1m0s
[06:08:18.718] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:14:0)
[06:08:18.718] Timer tick, asking Buildbucket for the build status
[06:08:18.893] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:18.893] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:15:0) after 1m0s
[06:09:18.976] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:15:0)
[06:09:18.976] Timer tick, asking Buildbucket for the build status
[06:09:19.477] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:19.477] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:16:0) after 1m0s
[06:10:19.484] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:16:0)
[06:10:19.484] Timer tick, asking Buildbucket for the build status
[06:10:20.360] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:20.360] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:17:0) after 1m0s
[06:11:20.434] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:17:0)
[06:11:20.434] Timer tick, asking Buildbucket for the build status
[06:11:20.603] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:20.603] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:18:0) after 1m0s
[06:12:20.637] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:18:0)
[06:12:20.637] Timer tick, asking Buildbucket for the build status
[06:12:21.139] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:21.139] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:19:0) after 1m0s
[06:13:21.124] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:19:0)
[06:13:21.124] Timer tick, asking Buildbucket for the build status
[06:13:21.364] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:21.364] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:20:0) after 1m0s
[06:14:21.417] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:20:0)
[06:14:21.417] Timer tick, asking Buildbucket for the build status
[06:14:22.054] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:22.054] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:21:0) after 1m0s
[06:15:22.103] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:21:0)
[06:15:22.103] Timer tick, asking Buildbucket for the build status
[06:15:22.328] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:22.328] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:22:0) after 1m0s
[06:16:22.485] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:22:0)
[06:16:22.485] Timer tick, asking Buildbucket for the build status
[06:16:22.672] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:22.672] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:23:0) after 1m0s
[06:17:22.749] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:23:0)
[06:17:22.749] Timer tick, asking Buildbucket for the build status
[06:17:22.910] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:22.910] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:24:0) after 1m0s
[06:18:22.960] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:24:0)
[06:18:22.960] Timer tick, asking Buildbucket for the build status
[06:18:23.702] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:23.703] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:25:0) after 1m0s
[06:19:23.741] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:25:0)
[06:19:23.741] Timer tick, asking Buildbucket for the build status
[06:19:24.112] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:24.112] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:26:0) after 1m0s
[06:20:24.192] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:26:0)
[06:20:24.192] Timer tick, asking Buildbucket for the build status
[06:20:24.401] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:24.401] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:27:0) after 1m0s
[06:21:24.419] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:27:0)
[06:21:24.419] Timer tick, asking Buildbucket for the build status
[06:21:24.571] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:24.571] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:28:0) after 1m0s
[06:22:24.637] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:28:0)
[06:22:24.637] Timer tick, asking Buildbucket for the build status
[06:22:25.229] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:25.229] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:29:0) after 1m0s
[06:23:25.282] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:29:0)
[06:23:25.282] Timer tick, asking Buildbucket for the build status
[06:23:25.435] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:25.435] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:30:0) after 1m0s
[06:24:25.483] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:30:0)
[06:24:25.483] Timer tick, asking Buildbucket for the build status
[06:24:26.006] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:26.006] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:31:0) after 1m0s
[06:25:26.038] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:31:0)
[06:25:26.038] Timer tick, asking Buildbucket for the build status
[06:25:26.256] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:26.256] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:32:0) after 1m0s
[06:26:26.243] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:32:0)
[06:26:26.243] Timer tick, asking Buildbucket for the build status
[06:26:26.713] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:26.713] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:33:0) after 1m0s
[06:27:26.773] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:33:0)
[06:27:26.773] Timer tick, asking Buildbucket for the build status
[06:27:26.970] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:26.970] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:34:0) after 1m0s
[06:28:27.016] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:34:0)
[06:28:27.016] Timer tick, asking Buildbucket for the build status
[06:28:27.239] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:27.239] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:35:0) after 1m0s
[06:29:27.272] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:35:0)
[06:29:27.272] Timer tick, asking Buildbucket for the build status
[06:29:27.629] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:27.629] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:36:0) after 1m0s
[06:30:27.673] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:36:0)
[06:30:27.673] Timer tick, asking Buildbucket for the build status
[06:30:27.892] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:27.892] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:37:0) after 1m0s
[06:31:27.922] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:37:0)
[06:31:27.922] Timer tick, asking Buildbucket for the build status
[06:31:28.921] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:28.921] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:38:0) after 1m0s
[06:32:28.954] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:38:0)
[06:32:28.954] Timer tick, asking Buildbucket for the build status
[06:32:32.205] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:32.205] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:39:0) after 1m0s
[06:33:32.336] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:39:0)
[06:33:32.336] Timer tick, asking Buildbucket for the build status
[06:33:32.799] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:32.799] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:40:0) after 1m0s
[06:34:32.936] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:40:0)
[06:34:32.936] Timer tick, asking Buildbucket for the build status
[06:34:33.410] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:33.410] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:41:0) after 1m0s
[06:35:33.461] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:41:0)
[06:35:33.461] Timer tick, asking Buildbucket for the build status
[06:35:34.037] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:34.038] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:42:0) after 1m0s
[06:36:34.075] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:42:0)
[06:36:34.075] Timer tick, asking Buildbucket for the build status
[06:36:34.766] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:34.766] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:43:0) after 1m0s
[06:37:34.792] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:43:0)
[06:37:34.792] Timer tick, asking Buildbucket for the build status
[06:37:35.129] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:35.129] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:44:0) after 1m0s
[06:38:35.160] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:44:0)
[06:38:35.160] Timer tick, asking Buildbucket for the build status
[06:38:35.376] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:35.376] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:45:0) after 1m0s
[06:39:35.395] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:45:0)
[06:39:35.395] Timer tick, asking Buildbucket for the build status
[06:39:35.578] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:35.578] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:46:0) after 1m0s
[06:40:35.596] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:46:0)
[06:40:35.596] Timer tick, asking Buildbucket for the build status
[06:40:35.740] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:35.740] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:47:0) after 1m0s
[06:41:35.815] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:47:0)
[06:41:35.815] Timer tick, asking Buildbucket for the build status
[06:41:37.102] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:37.102] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:48:0) after 1m0s
[06:42:37.104] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:48:0)
[06:42:37.104] Timer tick, asking Buildbucket for the build status
[06:42:37.322] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:37.322] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:49:0) after 1m0s
[06:43:37.347] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:49:0)
[06:43:37.347] Timer tick, asking Buildbucket for the build status
[06:43:37.515] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:37.515] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:50:0) after 1m0s
[06:44:37.540] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:50:0)
[06:44:37.540] Timer tick, asking Buildbucket for the build status
[06:44:38.427] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:38.427] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:51:0) after 1m0s
[06:45:38.468] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:51:0)
[06:45:38.468] Timer tick, asking Buildbucket for the build status
[06:45:38.712] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:38.712] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:52:0) after 1m0s
[06:46:38.732] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:52:0)
[06:46:38.732] Timer tick, asking Buildbucket for the build status
[06:46:39.573] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:39.573] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:53:0) after 1m0s
[06:47:39.605] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:53:0)
[06:47:39.605] Timer tick, asking Buildbucket for the build status
[06:47:39.772] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:39.772] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:54:0) after 1m0s
[06:48:39.815] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:54:0)
[06:48:39.815] Timer tick, asking Buildbucket for the build status
[06:48:40.118] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:40.118] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:55:0) after 1m0s
[06:49:40.240] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:55:0)
[06:49:40.240] Timer tick, asking Buildbucket for the build status
[06:49:40.436] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:40.436] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:56:0) after 1m0s
[06:50:40.535] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:56:0)
[06:50:40.535] Timer tick, asking Buildbucket for the build status
[06:50:40.678] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:40.678] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:57:0) after 1m0s
[06:51:40.738] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:57:0)
[06:51:40.738] Timer tick, asking Buildbucket for the build status
[06:51:40.903] Build 8890586967405128832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:40.903] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9056017029490150496:58:0) after 1m0s
[06:52:13.255] Received PubSub notification, asking Buildbucket for the build status
[06:52:13.811] Build 8890586967405128832: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[06:52:13.811] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1579675932377714",
"created_by": "project:chromium",
"created_ts": "1579672573617903",
"id": "8890586967405128832",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a94bda97ace74a74b06ca87303defce4df217a2f\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"jobs\": 500.0, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-34-ymhi\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 167673, \"got_angle_revision\": \"8c396a9cc4a83eaf8828dac7f0a39ed30714c58a\", \"got_dawn_revision\": \"80880ee9985484d39355d2827ce5cb9343a07e91\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"a94bda97ace74a74b06ca87303defce4df217a2f\", \"got_revision_cp\": \"refs/heads/master@{#733887}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"b766e5e7fbf42e48be36afc80e2c448e339c599e\", \"got_v8_revision\": \"4cde67a85fd4c29f78551e5aebca1fc8912876e8\", \"got_v8_revision_cp\": \"refs/heads/8.1.244@{#1}\", \"got_webrtc_revision\": \"06df1e1c4655a1048bfeb30e090ab52005f1e841\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30339}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a94bda97ace74a74b06ca87303defce4df217a2f\", \"swarm_hashes_refs/heads/master(at){#733887}_without_patch\": {\"accessibility_unittests\": \"28092d53582563cb11d11722309fad60115181ae\", \"angle_unittests\": \"29c0cf80a0e2115308eca7a0a66ad9741697aee7\", \"app_shell_unittests\": \"6b6d2d6e3fae8df3020328a6bfd7fabd64ee8d85\", \"aura_unittests\": \"b1e86f4a97ad36e5c60c8c149173355e6b4a50f2\", \"base_unittests\": \"88008a631ed3aa35914e18e9af3d82e0758ab7d5\", \"base_util_unittests\": \"fc34962902235ac18ca84ca65a13ccd082ddbfa0\", \"blink_common_unittests\": \"431e7d6b5bb61fffa8850e58403510534fa97934\", \"blink_fuzzer_unittests\": \"93cf6d6b62ecfd249a0fd3b7b59803a24eae8b3f\", \"blink_heap_unittests\": \"1b4b720c78ab33085c5ff9de9365a7ac34c8a957\", \"blink_platform_unittests\": \"b109cb40b4cfdddd08a5b0f111d31f4cae985b19\", \"blink_python_tests\": \"45959ceaa0e03a34b576d96ea46a821cd8898934\", \"blink_unittests\": \"0e2b57f148af522b51f6cdb0c95c6aefec338ac7\", \"blink_web_tests\": \"9bdc3fd8187b4c62a11fb425052e54e6552baf9a\", \"boringssl_crypto_tests\": \"461cc6c0d56144de44c3e9ad408dc00cdb47e109\", \"boringssl_ssl_tests\": \"ea9939c1d3004f41cdb5840bd92fc55bee1f4886\", \"browser_tests\": \"33f44f48e22ad450a352b78afb8ff780acbe3ffc\", \"cacheinvalidation_unittests\": \"73497498e9f8824388407299e0394d3e5d330e1d\", \"capture_unittests\": \"4b48be97c3790d1e09f0e72be2072a2fd031cbad\", \"cast_unittests\": \"aaec3d287594f23a76f5fc12e65a348f7f7b8bac\", \"cc_unittests\": \"b4624c0e7b961447aad071a8445fb1b18c8f0b58\", \"chrome_app_unittests\": \"42be65c651e92028571281b6871d97ca924ac31c\", \"chromedriver_unittests\": \"c604dc1c8d29b49e0f3276d73ae82f5e191e29c4\", \"components_browsertests\": \"3c0aac98569aa6323a5d394cc13ec6774b5fed73\", \"components_unittests\": \"286bc92e3f413843a2129985a2a898ece07c11d4\", \"compositor_unittests\": \"ed7d5a0aff512dc1bb96158ea7437fa1e7c0bc2e\", \"content_browsertests\": \"f2c2f804abbbd04e888abd11a7c56a5676883783\", \"content_shell_crash_test\": \"b92bfba8a9cc7d1e62dc3c2265e76c83ecda3c69\", \"content_unittests\": \"48601d931b6aefea39ba751286cd720196ab2fbe\", \"crashpad_tests\": \"492e583cb2c6358320c5b7c7c975618419d753ab\", \"cronet_tests\": \"b82643fea9940be396e3a2393fd429685279c10d\", \"cronet_unittests\": \"5f5abb69c9ffe6c207b4b5498d84110fba516543\", \"crypto_unittests\": \"0b90e875f383334d038c7319a9dc3dc419d1f6cf\", \"dbus_unittests\": \"4711535e2c5b550031b9829f1ae3aa821cddaf15\", \"device_unittests\": \"39568a33e2540997ff001dbda8013291d7f3106a\", \"display_unittests\": \"d1226a1ed14e7c9cc45455f5bb18f0afad7c75c2\", \"events_unittests\": \"2822dcb466bbeb93fd454a4f468176c49a4f2173\", \"extensions_browsertests\": \"083de2ccb04b41667b78a89a2801367a5d226985\", \"extensions_unittests\": \"70f72fa02e471081e2f65fbbadd7df1bcda0464b\", \"filesystem_service_unittests\": \"c0b364db95fee28b5eea9f0fe0d8b1517eaf7886\", \"flatbuffers_unittests\": \"92c174f535dbe8e15f58589d59dc88efc29a10d4\", \"gcm_unit_tests\": \"65c621a59d73e87c36e0ee20c11284a98996431a\", \"gfx_unittests\": \"93dceb8d22a2117ae1ada90354b010b6dc57479f\", \"gin_unittests\": \"6c38c1895f0e0d9b5062b1f31b4fef91ad94c255\", \"google_apis_unittests\": \"90af705c1a4eeacc3fe76c75d80c9e7b0bc2913d\", \"gpu_unittests\": \"663281af095e89b83c78880ae554ec0e88bf3254\", \"grit_python_unittests\": \"5ba70f2d123ffe8977591ea0b82d6185e7fe166f\", \"gwp_asan_unittests\": \"598b2b92ea3c87f570e8ad04201a6e1cac61e94d\", \"headless_browsertests\": \"709c133f723b44c825ea52282ea27a7751ab049b\", \"headless_unittests\": \"b7de37521dc50fab4c73cd0e6999dc63600681c2\", \"interactive_ui_tests\": \"e423b8fa3c89b2477f9ae3440587402d9d79652b\", \"ipc_tests\": \"1a31bb2dd4a6f891bb861c13ef447bcabec0b182\", \"jingle_unittests\": \"1cbb407d162172e68c6355e29dc8a4fb97b7a430\", \"latency_unittests\": \"ddab612da439d7c7938ab577adf8bdc5cfda99a4\", \"libjingle_xmpp_unittests\": \"2a1b644b2a2df5d14f45d62e0e3e79902a98f33f\", \"media_blink_unittests\": \"ca6c2bd54d1eef0d75766a87c96f2e41238e8fc7\", \"media_service_unittests\": \"650ba74d527f8933675006eb8a2fd9146c3bded8\", \"media_unittests\": \"511754dce69a023897edbaf9079a32eebaa6fdb1\", \"message_center_unittests\": \"c62c5539f88ecc1293442dd0fd1d7b3ffd7ceab2\", \"metrics_python_tests\": \"18998f9f18cb29f6d5d92b5ad929439b2836d893\", \"midi_unittests\": \"80c1935016b080578074077d9861015457d965a0\", \"mojo_core_unittests\": \"0e5bdec0e9514f453f948d6faf9185d07b4ca729\", \"mojo_unittests\": \"44b9d9fa8f79d6c8b681809044f1755c9d44d71f\", \"nacl_helper_nonsfi_unittests\": \"b9ac89d54d78249f4e710e2b79cb100c4d97f574\", \"nacl_loader_unittests\": \"0fb68b5d985704ac3d7a88136f1fa06bc36815d5\", \"native_theme_unittests\": \"ea3beee38c367f6e16ab5f5e2f1d17f4c773a9ce\", \"net_unittests\": \"5257866a2ddaf3f835692ee2a7c1d846e3fa2335\", \"pdf_unittests\": \"8180b66e6f7370ef7da2810777fb449d61a43062\", \"perfetto_unittests\": \"6ef1f342e5dde004d865bc1f0b7fdc517ded47c3\", \"ppapi_unittests\": \"367a772af8ea3a94ee7c8aa63dfe3fd2562c3aed\", \"printing_unittests\": \"b02fd2fc453091016e8d24d09a0d4fa938057152\", \"remoting_unittests\": \"5d46078d8ad208d0ccec9f0ad22d7671f7ea463a\", \"sandbox_linux_unittests\": \"4136f03cd3bd84ff5fa008f0a92c50e568009892\", \"service_manager_unittests\": \"525326d5e2096b5b19c3241b07315c797c811380\", \"services_unittests\": \"122fa382d3aea752ca41b43b6ef275da811add3d\", \"shell_dialogs_unittests\": \"5051cc6f887ad6ee9ec12a92c3b7ccf58b81d9fc\", \"skia_unittests\": \"2cbf992d44d3f510eea9d32ac15f18bc8c394af3\", \"snapshot_unittests\": \"f64a082414647bead630d9a8b4524ee81aefd380\", \"sql_unittests\": \"7ea20f273dbf479ef9e4cb66aaee57b953df7350\", \"storage_unittests\": \"3cdf4ab30e9a07fd4050c40cc61b48fd10e394de\", \"sync_integration_tests\": \"ae2af4b571a24194909a56bcb0d66efe70717637\", \"telemetry_gpu_unittests\": \"0232daa7bbecc8941e46ea62578f72edcd0377b1\", \"telemetry_perf_unittests\": \"5407bf92bbedfd243e90272431518f2d8db993eb\", \"telemetry_unittests\": \"2106d0a8f977456cd599ff96c82512912e795d21\", \"traffic_annotation_auditor_unittests\": \"68be1c9b800f8b313b342f3d2699d8f2607eab5f\", \"ui_base_unittests\": \"ccb2bd2c6e4d0110356a6356c7dd07e5434b7874\", \"ui_touch_selection_unittests\": \"1e3a6cfc21787d365dc358e757f26d1e96aec86d\", \"unit_tests\": \"d1a5d19eb5e5c41836f28bdab64dbca70b4877ea\", \"url_unittests\": \"773c624eb569bfccd481a8392e9c96358b49538f\", \"views_perftests\": \"94548d7022603590e16e803b72f33285ea70a80c\", \"views_unittests\": \"0d2a9ef80af1cd72a3f5976be737a7e4c1d599c3\", \"viz_unittests\": \"7b2357067426f33d9092d161c28042524c91545f\", \"vr_common_unittests\": \"db57fca5b404fbc40a9ffcd134a5fef4db1892f4\", \"vr_pixeltests\": \"766a0a138626f05a92a9c6b763a6f8253f701eb8\", \"webdriver_wpt_tests\": \"485cc693aa48a594d827429d652ca3f11bdf2832\", \"weblayer_browsertests\": \"645ac2c340a4bc39ed48dd0e577ad93134f2f27d\", \"wm_unittests\": \"2c2b73a90d48a01e1217c363202fbd2b6694615b\", \"wtf_unittests\": \"d978864b9c207cb14dc64612ac37c9f94c7f8087\", \"xr_browser_tests\": \"0d3e3def2f639907f4425e83e4da90ec2537bfb8\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-34-ymhi\"], \"image\": [\"chrome-xenial-20011200-47b90f1b63b\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4792-2014df3\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1579672861627090",
"status": "COMPLETED",
"status_changed_ts": "1579675932654385",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/167673",
"builder:Linux Builder (dbg)",
"buildset:commit/git/a94bda97ace74a74b06ca87303defce4df217a2f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a94bda97ace74a74b06ca87303defce4df217a2f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9056017029490150496",
"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/8890586967405128832/+/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:49e4a4219d444b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1579675932667940",
"url": "https://ci.chromium.org/b/8890586967405128832",
"utcnow_ts": "1579675933797147"
}
}
[06:52:13.811] Invocation finished in 56m1.566017878s with status SUCCEEDED