[14:57:14.283] New invocation is queued and will start shortly
[14:57:15.410] Starting the invocation (attempt 1)
[14:57:15.451] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:57:15.451] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:57:15.451] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9061509405550852528",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"8ee4214852841a39f9f0a795b2ea97b0abd13e66\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9061509405550852528",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/8ee4214852841a39f9f0a795b2ea97b0abd13e66",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8ee4214852841a39f9f0a795b2ea97b0abd13e66",
"gitiles_ref:refs/heads/master"
]
}
[14:57:16.424] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1574434635620773",
"id": "8896079343478142752",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8ee4214852841a39f9f0a795b2ea97b0abd13e66\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1574434636217544",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/165155",
"builder:Linux Builder (dbg)",
"buildset:commit/git/8ee4214852841a39f9f0a795b2ea97b0abd13e66",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8ee4214852841a39f9f0a795b2ea97b0abd13e66",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9061509405550852528",
"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/8896079343478142752/+/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": "1574434636217872",
"url": "https://ci.chromium.org/b/8896079343478142752",
"utcnow_ts": "1574434636412461"
}
}
[14:57:16.424] Task URL: https://ci.chromium.org/b/8896079343478142752
[14:57:16.424] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:2:0) after 1m0s
[14:57:41.363] Received PubSub notification, asking Buildbucket for the build status
[14:57:41.648] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:16.536] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:2:0)
[14:58:16.536] Timer tick, asking Buildbucket for the build status
[14:58:16.759] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:16.759] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:4:0) after 1m0s
[14:59:16.940] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:4:0)
[14:59:16.940] Timer tick, asking Buildbucket for the build status
[14:59:17.084] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:17.084] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:5:0) after 1m0s
[15:00:17.178] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:5:0)
[15:00:17.178] Timer tick, asking Buildbucket for the build status
[15:00:17.334] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:17.334] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:6:0) after 1m0s
[15:01:17.351] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:6:0)
[15:01:17.351] Timer tick, asking Buildbucket for the build status
[15:01:18.451] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:18.451] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:7:0) after 1m0s
[15:02:18.600] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:7:0)
[15:02:18.600] Timer tick, asking Buildbucket for the build status
[15:02:18.767] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:18.767] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:8:0) after 1m0s
[15:03:18.900] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:8:0)
[15:03:18.900] Timer tick, asking Buildbucket for the build status
[15:03:19.169] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:19.169] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:9:0) after 1m0s
[15:04:19.253] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:9:0)
[15:04:19.253] Timer tick, asking Buildbucket for the build status
[15:04:19.647] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:19.647] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:10:0) after 1m0s
[15:05:19.767] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:10:0)
[15:05:19.767] Timer tick, asking Buildbucket for the build status
[15:05:19.896] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:19.896] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:11:0) after 1m0s
[15:06:20.056] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:11:0)
[15:06:20.056] Timer tick, asking Buildbucket for the build status
[15:06:20.715] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:20.715] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:12:0) after 1m0s
[15:07:20.779] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:12:0)
[15:07:20.779] Timer tick, asking Buildbucket for the build status
[15:07:21.149] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:21.149] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:13:0) after 1m0s
[15:08:21.205] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:13:0)
[15:08:21.205] Timer tick, asking Buildbucket for the build status
[15:08:21.506] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:21.506] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:14:0) after 1m0s
[15:09:21.541] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:14:0)
[15:09:21.541] Timer tick, asking Buildbucket for the build status
[15:09:21.735] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:21.735] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:15:0) after 1m0s
[15:10:21.805] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:15:0)
[15:10:21.805] Timer tick, asking Buildbucket for the build status
[15:10:22.327] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:22.327] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:16:0) after 1m0s
[15:11:22.345] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:16:0)
[15:11:22.345] Timer tick, asking Buildbucket for the build status
[15:11:23.606] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:23.606] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:17:0) after 1m0s
[15:12:23.649] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:17:0)
[15:12:23.649] Timer tick, asking Buildbucket for the build status
[15:12:23.934] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:23.934] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:18:0) after 1m0s
[15:13:23.953] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:18:0)
[15:13:23.953] Timer tick, asking Buildbucket for the build status
[15:13:24.153] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:24.153] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:19:0) after 1m0s
[15:14:24.178] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:19:0)
[15:14:24.178] Timer tick, asking Buildbucket for the build status
[15:14:24.326] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:24.326] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:20:0) after 1m0s
[15:15:24.388] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:20:0)
[15:15:24.388] Timer tick, asking Buildbucket for the build status
[15:15:25.297] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:25.297] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:21:0) after 1m0s
[15:16:25.384] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:21:0)
[15:16:25.384] Timer tick, asking Buildbucket for the build status
[15:16:26.606] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:26.606] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:22:0) after 1m0s
[15:17:26.672] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:22:0)
[15:17:26.672] Timer tick, asking Buildbucket for the build status
[15:17:26.924] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:26.924] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:23:0) after 1m0s
[15:18:26.959] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:23:0)
[15:18:26.959] Timer tick, asking Buildbucket for the build status
[15:18:27.138] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:27.138] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:24:0) after 1m0s
[15:19:27.146] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:24:0)
[15:19:27.146] Timer tick, asking Buildbucket for the build status
[15:19:27.288] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:27.288] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:25:0) after 1m0s
[15:20:27.321] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:25:0)
[15:20:27.321] Timer tick, asking Buildbucket for the build status
[15:20:27.472] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:27.472] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:26:0) after 1m0s
[15:21:27.528] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:26:0)
[15:21:27.528] Timer tick, asking Buildbucket for the build status
[15:21:27.771] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:27.771] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:27:0) after 1m0s
[15:22:27.859] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:27:0)
[15:22:27.859] Timer tick, asking Buildbucket for the build status
[15:22:28.053] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:28.053] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:28:0) after 1m0s
[15:23:28.071] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:28:0)
[15:23:28.071] Timer tick, asking Buildbucket for the build status
[15:23:28.279] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:28.279] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:29:0) after 1m0s
[15:24:28.343] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:29:0)
[15:24:28.343] Timer tick, asking Buildbucket for the build status
[15:24:29.235] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:29.235] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:30:0) after 1m0s
[15:25:29.279] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:30:0)
[15:25:29.279] Timer tick, asking Buildbucket for the build status
[15:25:30.215] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:30.215] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:31:0) after 1m0s
[15:26:30.292] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:31:0)
[15:26:30.292] Timer tick, asking Buildbucket for the build status
[15:26:30.915] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:30.916] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:32:0) after 1m0s
[15:27:30.946] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:32:0)
[15:27:30.946] Timer tick, asking Buildbucket for the build status
[15:27:31.128] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:31.131] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:33:0) after 1m0s
[15:28:31.169] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:33:0)
[15:28:31.169] Timer tick, asking Buildbucket for the build status
[15:28:31.530] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:31.530] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:34:0) after 1m0s
[15:29:31.618] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:34:0)
[15:29:31.618] Timer tick, asking Buildbucket for the build status
[15:29:32.221] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:32.221] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:35:0) after 1m0s
[15:30:32.525] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:35:0)
[15:30:32.525] Timer tick, asking Buildbucket for the build status
[15:30:33.227] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:33.227] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:36:0) after 1m0s
[15:31:33.333] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:36:0)
[15:31:33.333] Timer tick, asking Buildbucket for the build status
[15:31:33.617] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:33.617] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:37:0) after 1m0s
[15:32:33.771] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:37:0)
[15:32:33.771] Timer tick, asking Buildbucket for the build status
[15:32:34.014] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:34.014] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:38:0) after 1m0s
[15:33:34.066] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:38:0)
[15:33:34.067] Timer tick, asking Buildbucket for the build status
[15:33:34.624] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:34.624] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:39:0) after 1m0s
[15:34:34.766] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:39:0)
[15:34:34.766] Timer tick, asking Buildbucket for the build status
[15:34:35.096] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:35.096] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:40:0) after 1m0s
[15:35:35.098] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:40:0)
[15:35:35.098] Timer tick, asking Buildbucket for the build status
[15:35:35.390] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:35.390] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:41:0) after 1m0s
[15:36:35.483] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:41:0)
[15:36:35.483] Timer tick, asking Buildbucket for the build status
[15:36:35.668] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:35.669] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:42:0) after 1m0s
[15:37:35.741] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:42:0)
[15:37:35.741] Timer tick, asking Buildbucket for the build status
[15:37:36.248] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:36.248] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:43:0) after 1m0s
[15:38:36.335] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:43:0)
[15:38:36.335] Timer tick, asking Buildbucket for the build status
[15:38:36.452] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:36.452] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:44:0) after 1m0s
[15:39:36.458] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:44:0)
[15:39:36.458] Timer tick, asking Buildbucket for the build status
[15:39:36.660] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:36.660] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:45:0) after 1m0s
[15:40:36.752] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:45:0)
[15:40:36.752] Timer tick, asking Buildbucket for the build status
[15:40:36.948] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:36.948] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:46:0) after 1m0s
[15:41:36.970] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:46:0)
[15:41:36.970] Timer tick, asking Buildbucket for the build status
[15:41:37.201] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:37.201] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:47:0) after 1m0s
[15:42:37.309] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:47:0)
[15:42:37.309] Timer tick, asking Buildbucket for the build status
[15:42:37.472] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:37.472] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:48:0) after 1m0s
[15:43:37.573] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:48:0)
[15:43:37.573] Timer tick, asking Buildbucket for the build status
[15:43:37.791] Build 8896079343478142752: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:37.791] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9061509405550852528:49:0) after 1m0s
[15:43:50.368] Received PubSub notification, asking Buildbucket for the build status
[15:43:50.541] Build 8896079343478142752: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:43:50.542] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1574437429524210",
"created_by": "project:chromium",
"created_ts": "1574434635620773",
"id": "8896079343478142752",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8ee4214852841a39f9f0a795b2ea97b0abd13e66\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"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-central1-b-8-51-00r4\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 165155, \"got_angle_revision\": \"b868861c3478a8a71db49abedd74704d4bb718c0\", \"got_dawn_revision\": \"540ababb6bee89c96e4eb7beed14e1dbcc377148\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"8ee4214852841a39f9f0a795b2ea97b0abd13e66\", \"got_revision_cp\": \"refs/heads/master@{#718128}\", \"got_swarming_client_revision\": \"885b3febcc170a60f25795304e60927b77d1e92d\", \"got_swiftshader_revision\": \"131a1ba8d06a669ac48fb792b76c78d20f9e2e74\", \"got_v8_revision\": \"0f25fee9feac6e11e3022618fd22912f015011b1\", \"got_v8_revision_cp\": \"refs/heads/8.0.342@{#1}\", \"got_webrtc_revision\": \"15f2ff46102741738683298f6d60158fa55b67a1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#29870}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8ee4214852841a39f9f0a795b2ea97b0abd13e66\", \"swarm_hashes_refs/heads/master(at){#718128}_without_patch\": {\"accessibility_unittests\": \"fc9569a52c88fefc1f777eb91e824b50fdd87c9c\", \"angle_unittests\": \"0fef7d515bcaf5ef77e52878d72f92b99b112e9a\", \"app_shell_unittests\": \"b9e1d73baed869b6b4f1425b3b2b4e1db3c82a4f\", \"aura_unittests\": \"d43412f125e1948582561773b28ec8cc30c5b3ce\", \"base_unittests\": \"f68c2de63bbc9a00c9870e3fd2da8735001ef41e\", \"base_util_unittests\": \"396571aad12d9fe5865feae9b8e39808d45fde30\", \"blink_common_unittests\": \"54eb8ecbd58c3d7f34b11253227121a2bc207bbe\", \"blink_fuzzer_unittests\": \"06a4f10769b9bc134206f639f443c9846986d9e1\", \"blink_heap_unittests\": \"fba300cc6161bad4bfb8ce5f30d299c06015cef0\", \"blink_platform_unittests\": \"920590451fe8d1d5e48f4915358ad826b9c198fd\", \"blink_python_tests\": \"a01f19608caffe911d1765c9526a213884048caa\", \"blink_unittests\": \"efc561c20bae346dd7e99771668b4d8b0c27fd79\", \"blink_web_tests_exparchive\": \"24cfe87cc98d981990882888b9d43e4cffb62545\", \"boringssl_crypto_tests\": \"617d8a78ae5a5d4bb368fe72665f6b7ec68b2b49\", \"boringssl_ssl_tests\": \"0f6c7b1008c6c0c3aa7ba55c0231cea50a756674\", \"browser_tests\": \"575ecbedcad957a5790c4eccc93d16ceba4975e8\", \"cacheinvalidation_unittests\": \"9fc19f209b4e00c93ac639068eae7e43ac5c5b52\", \"capture_unittests\": \"29c5821dbb75d6409fde8828c4bc6fc97c4101fb\", \"cast_unittests\": \"14a051e9078a153128601e0a139d3d01a4e05180\", \"cc_unittests\": \"8410a12e7d1ea46d75ff6cec5b869944e2793e01\", \"chrome_app_unittests\": \"d05e56906cd891c9523026e74f6a4141d8011147\", \"chromedriver_unittests\": \"159997e8d696c344c262aaecb12c88acc8bc1b17\", \"color_unittests\": \"04b3531bd13321ac331135b46acbf6bd2e604816\", \"components_browsertests\": \"c3f9682bd5652dd3cbfeac1f228d7877b29c8d76\", \"components_unittests\": \"10e5321f863f296480ae038ec980dc42cfa2e98a\", \"compositor_unittests\": \"c5881453a7af32631c2b969273ba2c77dc822778\", \"content_browsertests\": \"23750f5c5ceba7afb5c65c53d619ecc75ddc80d7\", \"content_shell_crash_test\": \"a1cea8f59da3891568bcca8f0b987f9a218465c5\", \"content_unittests\": \"a727b0f9ef32424e07dd75b71fb5dc0a11e934bc\", \"crashpad_tests\": \"18a467a8298c83e09e49692f3d2f76ed7308cd9f\", \"cronet_tests\": \"6fa62995d96ba3449466dbcc802d1fbd37083010\", \"cronet_unittests\": \"307ca0727604253d4af15d4bcbcbcc861b9446aa\", \"crypto_unittests\": \"f872c6b7d029cd7a9eb7164fcee7a5120e8684c0\", \"dbus_unittests\": \"e1ca3d9d78a1fccd8ed529ac07167ea71d3a8c06\", \"device_unittests\": \"fe3a8f69bcff484221b19a0b33ad75635e851436\", \"devtools_lint_check\": \"4951ac131a2741f2642636b6720b485a7539faee\", \"devtools_type_check\": \"d341060abb8cea6748d6a19c27bfd10fe993ee99\", \"display_unittests\": \"203f5ec57e3a68c7c567fc21bd8559f8cea5029d\", \"events_unittests\": \"35c08ac4094cbb828cfe302b692bb2381f4fd94a\", \"extensions_browsertests\": \"ece3b24d21e0cc0934d4bbabac9f511dbb996ebf\", \"extensions_unittests\": \"bdba1d77632b990e02b83133871ed09244ff6b38\", \"filesystem_service_unittests\": \"5c713d094e11fb7bc1dc5cf23a68fc6d5a2edb4e\", \"flatbuffers_unittests\": \"68877d8b1262b0aa771438df1015a16a10098d5e\", \"gcm_unit_tests\": \"45e806886a40d4c73ce52deb37ef7e3ea9ad2562\", \"gfx_unittests\": \"3a7ced86e1888edc6e5a2403f7446704eacfd49a\", \"gin_unittests\": \"f143339bf1fa6758a1dcd0dcd8d1cff72e7572ae\", \"google_apis_unittests\": \"271de96237b498333821d58aee1f863604843eb8\", \"gpu_unittests\": \"6ade00d01cdcfe47073a95e4c26a067aa9238d39\", \"grit_python_unittests\": \"4267cdfe96cbb5881cc25cc5b15aab4b2f28c5f5\", \"gwp_asan_unittests\": \"91de107a5f98cac5c0d080d5e8f188169c1d1757\", \"headless_browsertests\": \"1c9ca4b78d6d3d2de8f6a3eb5db37ab8d0996074\", \"headless_unittests\": \"5dbc703ffbd72bc31183d7c1c6b0a8f31808e1ef\", \"interactive_ui_tests\": \"d438e2554f11d3e6c47bd32067a91e9bb5f098ed\", \"ipc_tests\": \"03dae3ca0aca77279243e08c173f7c8368b1eb36\", \"jingle_unittests\": \"ba0dd5d4534c9b902a1d4b3e32d44775811e6e4a\", \"latency_unittests\": \"2bba0da2f2a3abd3f6f11491ec57bd2ecefdb869\", \"libjingle_xmpp_unittests\": \"101bb52c062232043d7e7b9494691436e7bdfec9\", \"media_blink_unittests\": \"3e3aec28b95b5b2feca0e4627d2e83782bbd2a9c\", \"media_service_unittests\": \"e9fecc1dc83b4ae703b716bd00dfe551a08df081\", \"media_unittests\": \"d08c5dede656fc44351269607be7f0255503eb90\", \"message_center_unittests\": \"d513cda0a78aea4cd12a9d95beb83b70abc0bb20\", \"metrics_python_tests\": \"10e08d23b20d0f7d35eac867a80d32dc8272ea6c\", \"midi_unittests\": \"dd1bbebadcc2d383bfbc030d1f3c312590dc880e\", \"mojo_core_unittests\": \"414f99b7beefc76479f8fd0fe958709b19e4af53\", \"mojo_unittests\": \"456e628a1ac39c7b9f4a09a514b56e7e37dc6d1d\", \"nacl_helper_nonsfi_unittests\": \"34156bebb7b6cf66fe1fc76198043b53296a28d4\", \"nacl_loader_unittests\": \"39e66da131e03e8d3ce869302da5abab2d190f46\", \"native_theme_unittests\": \"1fe9e4021d4eecd49898dfc03ad199c1e128fe57\", \"net_unittests\": \"e873fca447e5900897f095af1401f340d461bb3d\", \"pdf_unittests\": \"343fc232a25b61057e4cd72e29e16b2b6393f856\", \"perfetto_unittests\": \"96f676a4859fe303650e2021e40990e61d39e9e2\", \"ppapi_unittests\": \"f236d0897e89e822f95ad03e35d4fc41b34de93b\", \"printing_unittests\": \"37bbaa1ee7d37b90dccccf1be9a45fb7911ede71\", \"remoting_unittests\": \"0bd2e7d1ecccaea70f7bdc2f191982ef0a680745\", \"sandbox_linux_unittests\": \"893a05feddecaa0c23670c96ceb7b089a5a31136\", \"service_manager_unittests\": \"e1cc227e2867e785699fb79c2492bcc4d7984721\", \"services_unittests\": \"1e7c5642cc6ddeaf182164c90d796b650da704fb\", \"shell_dialogs_unittests\": \"716b91aa70bfcc3f83ccf4b090b507fe68eb5435\", \"skia_unittests\": \"df66c6df6b6d92b763ef2be9647ed6ed57c2b413\", \"snapshot_unittests\": \"1b9b70cfd831643659f3be4fcb2b7091161720e8\", \"sql_unittests\": \"30154484fd927ce4555676c4844b413bb49bfca0\", \"storage_unittests\": \"3340c3ea1d0777503799115531c03b075b7163cf\", \"sync_integration_tests\": \"ecb72269d2ed7b9a1d6a9b6c2c47f163e9bfbca2\", \"telemetry_gpu_unittests\": \"f2a71a8179582eda6fb1e9550b2870f47cc8eb6b\", \"telemetry_perf_unittests\": \"c780ea78abd1a3ea8747742472f255eb67c32402\", \"telemetry_unittests\": \"2e85151ef942efded87d53e283a5471b82ee399d\", \"traffic_annotation_auditor_unittests\": \"1d9896b39f1f578821c79396f029aceeccd52a6e\", \"ui_base_unittests\": \"d49468c5ac9dedc86c7212f55285941571e9f064\", \"ui_touch_selection_unittests\": \"257f66535cba3dd2cc79b9b158226fa061c7fb5d\", \"unit_tests\": \"1832c4ffde689bc48ad60085850e01b973f89b16\", \"url_unittests\": \"2a1f54b8a63026379da2b22bfacbd952bfdabb9c\", \"views_perftests\": \"2e7de72983e497bba83c03c94e6a6b41b27d3b0f\", \"views_unittests\": \"b0c6a814fb1242457f6f88408ce42e6096d5ba84\", \"viz_unittests\": \"fa6783ff5ece86ef6a45b54d0ca0cab8727ba1a2\", \"vr_common_unittests\": \"da2b97001afaba08b1cdd4f489a2846ea1ff12ab\", \"vr_pixeltests\": \"98777d985edd81086d0c4881fb210649779ebf6f\", \"webdriver_wpt_tests\": \"cd8f5ef4d341e27a96a7437132ab46db85bf3e4b\", \"weblayer_browsertests\": \"18fb963b46914e78587129eb6d67e6fe8f64c3a3\", \"wm_unittests\": \"df08ed6e22d722160b4c2ae0a549978180389764\", \"wtf_unittests\": \"e4a19152adadc0afc43be12fad0572cf09599c90\", \"xr_browser_tests\": \"e40c1d7e166d3a52480bb5c1523480c76f35d343\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_5018db700e530f8e7ae860b5b3feab2b02d3dedf1bbd3e704c6c59fde1b308dd_v2\", \"builder_61faa0d0c1c079ecf670d8cfa4f78ad3b1687a5534233c7377462c3ee6819708_v2\", \"builder_a432d290b72bc0f3cae401fbd647c33c14340f64e89770e9c9c39d6230495526_v2\", \"builder_a9a41095618141e4757e131cf792803c0a27ecf4e01eb5d0d372c002309e764f_v2\", \"builder_e21c17032b725bcf1c922976a36352546baf3f39ec148d1e6730a93c0400d8cc_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-central1-b-8-51-00r4\"], \"image\": [\"chrome-xenial-19111704-ac4c24a5ee0\"], \"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\": [\"4651-c74e250\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1574434660142479",
"status": "COMPLETED",
"status_changed_ts": "1574437430013015",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/165155",
"builder:Linux Builder (dbg)",
"buildset:commit/git/8ee4214852841a39f9f0a795b2ea97b0abd13e66",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8ee4214852841a39f9f0a795b2ea97b0abd13e66",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9061509405550852528",
"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/8896079343478142752/+/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:48ac6fcf2ccb0f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1574437430018942",
"url": "https://ci.chromium.org/b/8896079343478142752",
"utcnow_ts": "1574437430526444"
}
}
[15:43:50.542] Invocation finished in 46m36.281889651s with status SUCCEEDED