[15:18:12.461] New invocation is queued and will start shortly
[15:18:13.533] Starting the invocation (attempt 1)
[15:18:13.568] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:18:13.568] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:18:13.568] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9053354359273627296",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"501980d9012f2bdc15b1e4ddb5714784c9c936ab\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9053354359273627296",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/501980d9012f2bdc15b1e4ddb5714784c9c936ab",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/501980d9012f2bdc15b1e4ddb5714784c9c936ab",
"gitiles_ref:refs/heads/master"
]
}
[15:18:14.262] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1582211893660801",
"id": "8887924297352146512",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"501980d9012f2bdc15b1e4ddb5714784c9c936ab\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582211894090785",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168827",
"builder:Linux Builder (dbg)",
"buildset:commit/git/501980d9012f2bdc15b1e4ddb5714784c9c936ab",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/501980d9012f2bdc15b1e4ddb5714784c9c936ab",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053354359273627296",
"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/8887924297352146512/+/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": "1582211894090987",
"url": "https://ci.chromium.org/b/8887924297352146512",
"utcnow_ts": "1582211894245481"
}
}
[15:18:14.262] Task URL: https://ci.chromium.org/b/8887924297352146512
[15:18:14.262] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:2:0) after 1m0s
[15:19:14.377] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:2:0)
[15:19:14.377] Timer tick, asking Buildbucket for the build status
[15:19:14.661] Build 8887924297352146512: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:19:14.661] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:3:0) after 1m0s
[15:19:20.644] Received PubSub notification, asking Buildbucket for the build status
[15:19:20.947] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:14.770] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:3:0)
[15:20:14.770] Timer tick, asking Buildbucket for the build status
[15:20:14.932] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:14.932] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:5:0) after 1m0s
[15:21:15.028] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:5:0)
[15:21:15.028] Timer tick, asking Buildbucket for the build status
[15:21:15.123] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:15.123] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:6:0) after 1m0s
[15:22:15.187] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:6:0)
[15:22:15.187] Timer tick, asking Buildbucket for the build status
[15:22:15.292] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:15.292] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:7:0) after 1m0s
[15:23:15.459] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:7:0)
[15:23:15.459] Timer tick, asking Buildbucket for the build status
[15:23:16.374] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:16.374] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:8:0) after 1m0s
[15:24:16.472] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:8:0)
[15:24:16.472] Timer tick, asking Buildbucket for the build status
[15:24:16.594] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:16.594] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:9:0) after 1m0s
[15:25:16.716] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:9:0)
[15:25:16.716] Timer tick, asking Buildbucket for the build status
[15:25:16.837] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:16.837] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:10:0) after 1m0s
[15:26:16.876] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:10:0)
[15:26:16.876] Timer tick, asking Buildbucket for the build status
[15:26:16.979] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:16.979] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:11:0) after 1m0s
[15:27:17.132] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:11:0)
[15:27:17.132] Timer tick, asking Buildbucket for the build status
[15:27:17.418] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:17.418] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:12:0) after 1m0s
[15:28:17.617] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:12:0)
[15:28:17.617] Timer tick, asking Buildbucket for the build status
[15:28:17.822] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:17.822] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:13:0) after 1m0s
[15:29:18.106] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:13:0)
[15:29:18.106] Timer tick, asking Buildbucket for the build status
[15:29:18.285] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:18.285] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:14:0) after 1m0s
[15:30:18.498] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:14:0)
[15:30:18.498] Timer tick, asking Buildbucket for the build status
[15:30:18.661] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:18.661] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:15:0) after 1m0s
[15:31:18.690] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:15:0)
[15:31:18.690] Timer tick, asking Buildbucket for the build status
[15:31:18.972] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:18.972] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:16:0) after 1m0s
[15:32:19.136] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:16:0)
[15:32:19.136] Timer tick, asking Buildbucket for the build status
[15:32:19.298] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:19.298] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:17:0) after 1m0s
[15:33:19.418] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:17:0)
[15:33:19.418] Timer tick, asking Buildbucket for the build status
[15:33:19.761] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:19.761] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:18:0) after 1m0s
[15:34:19.866] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:18:0)
[15:34:19.866] Timer tick, asking Buildbucket for the build status
[15:34:19.962] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:19.962] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:19:0) after 1m0s
[15:35:20.014] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:19:0)
[15:35:20.014] Timer tick, asking Buildbucket for the build status
[15:35:20.222] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:20.222] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:20:0) after 1m0s
[15:36:20.398] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:20:0)
[15:36:20.398] Timer tick, asking Buildbucket for the build status
[15:36:20.491] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:20.491] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:21:0) after 1m0s
[15:37:20.585] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:21:0)
[15:37:20.585] Timer tick, asking Buildbucket for the build status
[15:37:20.704] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:20.704] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:22:0) after 1m0s
[15:38:20.695] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:22:0)
[15:38:20.695] Timer tick, asking Buildbucket for the build status
[15:38:20.808] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:20.808] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:23:0) after 1m0s
[15:39:20.899] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:23:0)
[15:39:20.899] Timer tick, asking Buildbucket for the build status
[15:39:20.997] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:20.997] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:24:0) after 1m0s
[15:40:21.006] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:24:0)
[15:40:21.006] Timer tick, asking Buildbucket for the build status
[15:40:21.128] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:21.128] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:25:0) after 1m0s
[15:41:21.522] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:25:0)
[15:41:21.522] Timer tick, asking Buildbucket for the build status
[15:41:21.607] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:21.607] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:26:0) after 1m0s
[15:42:22.570] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:26:0)
[15:42:22.570] Timer tick, asking Buildbucket for the build status
[15:42:22.705] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:22.705] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:27:0) after 1m0s
[15:43:22.785] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:27:0)
[15:43:22.785] Timer tick, asking Buildbucket for the build status
[15:43:22.925] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:22.925] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:28:0) after 1m0s
[15:44:23.030] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:28:0)
[15:44:23.030] Timer tick, asking Buildbucket for the build status
[15:44:23.179] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:23.179] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:29:0) after 1m0s
[15:45:23.327] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:29:0)
[15:45:23.327] Timer tick, asking Buildbucket for the build status
[15:45:23.435] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:23.435] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:30:0) after 1m0s
[15:46:23.449] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:30:0)
[15:46:23.449] Timer tick, asking Buildbucket for the build status
[15:46:23.723] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:23.723] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:31:0) after 1m0s
[15:47:23.798] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:31:0)
[15:47:23.798] Timer tick, asking Buildbucket for the build status
[15:47:23.909] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:23.909] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:32:0) after 1m0s
[15:48:23.936] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:32:0)
[15:48:23.936] Timer tick, asking Buildbucket for the build status
[15:48:24.035] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:24.035] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:33:0) after 1m0s
[15:49:24.120] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:33:0)
[15:49:24.120] Timer tick, asking Buildbucket for the build status
[15:49:24.280] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:24.280] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:34:0) after 1m0s
[15:50:24.366] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:34:0)
[15:50:24.366] Timer tick, asking Buildbucket for the build status
[15:50:24.789] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:24.789] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:35:0) after 1m0s
[15:51:24.888] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:35:0)
[15:51:24.888] Timer tick, asking Buildbucket for the build status
[15:51:25.044] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:25.044] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:36:0) after 1m0s
[15:52:25.099] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:36:0)
[15:52:25.099] Timer tick, asking Buildbucket for the build status
[15:52:25.204] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:25.204] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:37:0) after 1m0s
[15:53:25.366] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:37:0)
[15:53:25.366] Timer tick, asking Buildbucket for the build status
[15:53:25.525] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:25.525] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:38:0) after 1m0s
[15:54:25.723] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:38:0)
[15:54:25.723] Timer tick, asking Buildbucket for the build status
[15:54:25.814] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:25.814] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:39:0) after 1m0s
[15:55:26.079] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:39:0)
[15:55:26.079] Timer tick, asking Buildbucket for the build status
[15:55:26.198] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:26.198] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:40:0) after 1m0s
[15:56:26.296] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:40:0)
[15:56:26.296] Timer tick, asking Buildbucket for the build status
[15:56:26.395] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:26.395] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:41:0) after 1m0s
[15:57:26.536] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:41:0)
[15:57:26.536] Timer tick, asking Buildbucket for the build status
[15:57:26.677] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:26.677] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:42:0) after 1m0s
[15:58:26.811] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:42:0)
[15:58:26.811] Timer tick, asking Buildbucket for the build status
[15:58:27.073] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:27.073] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:43:0) after 1m0s
[15:59:27.292] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:43:0)
[15:59:27.292] Timer tick, asking Buildbucket for the build status
[15:59:27.404] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:27.404] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:44:0) after 1m0s
[16:00:27.547] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:44:0)
[16:00:27.547] Timer tick, asking Buildbucket for the build status
[16:00:27.777] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:27.777] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:45:0) after 1m0s
[16:01:27.885] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:45:0)
[16:01:27.885] Timer tick, asking Buildbucket for the build status
[16:01:28.164] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:28.165] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:46:0) after 1m0s
[16:02:28.289] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:46:0)
[16:02:28.289] Timer tick, asking Buildbucket for the build status
[16:02:28.401] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:28.401] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:47:0) after 1m0s
[16:03:28.505] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:47:0)
[16:03:28.505] Timer tick, asking Buildbucket for the build status
[16:03:28.768] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:28.768] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:48:0) after 1m0s
[16:04:28.880] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:48:0)
[16:04:28.880] Timer tick, asking Buildbucket for the build status
[16:04:29.401] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:29.401] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:49:0) after 1m0s
[16:05:29.543] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:49:0)
[16:05:29.543] Timer tick, asking Buildbucket for the build status
[16:05:29.754] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:29.754] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:50:0) after 1m0s
[16:06:29.885] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:50:0)
[16:06:29.885] Timer tick, asking Buildbucket for the build status
[16:06:29.994] Build 8887924297352146512: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:29.994] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053354359273627296:51:0) after 1m0s
[16:07:10.952] Received PubSub notification, asking Buildbucket for the build status
[16:07:11.028] Build 8887924297352146512: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:07:11.028] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1582214830159543",
"created_by": "project:chromium",
"created_ts": "1582211893660801",
"id": "8887924297352146512",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"501980d9012f2bdc15b1e4ddb5714784c9c936ab\"}}",
"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-central1-b-8-69-zljv\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168827, \"got_angle_revision\": \"b0e9cd6645e1d4e620db4d2a9a99597ee7620bd8\", \"got_dawn_revision\": \"b330ea6b491e3e000c11fe919e8099dece1c939b\", \"got_nacl_revision\": \"530e1f0dae34020864c81e5cd52be54d9c656690\", \"got_revision\": \"501980d9012f2bdc15b1e4ddb5714784c9c936ab\", \"got_revision_cp\": \"refs/heads/master@{#743103}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"74d3f371f2015deeedb985a8b24ba242ea65caef\", \"got_v8_revision\": \"6afd7913dd4deddb7882cc46ad4a9bb31586e1f3\", \"got_v8_revision_cp\": \"refs/heads/8.2.129@{#1}\", \"got_webrtc_revision\": \"1282babe6621d3a2084bbd24e77652e0b3a4eb93\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30549}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"501980d9012f2bdc15b1e4ddb5714784c9c936ab\", \"swarm_hashes_refs/heads/master(at){#743103}_without_patch\": {\"accessibility_unittests\": \"1f56c8568209d757481ffc1d9d8cf47f64db5f92\", \"angle_unittests\": \"3d75038c17cdfabcabbf14e13d7e5777da966c04\", \"app_shell_unittests\": \"54ed1807666d5222f8df4d1aa64502558dc8027b\", \"aura_unittests\": \"4ee2cd461d80d7288b4e0248ca4efbf0fdb5ae3f\", \"base_unittests\": \"c1ba544a9adc85c150a9ecbdc03a79f77890d206\", \"base_util_unittests\": \"1ab8aedec856f6c0ca7f7b285a208a9e3f86f39c\", \"blink_common_unittests\": \"2916dc737806e6a44b4733c0b3271952e39f9465\", \"blink_fuzzer_unittests\": \"279e5860d5cad7f20b0e08966180a0757644b5e2\", \"blink_heap_unittests\": \"6d8104a064259f089dc3bf7cee5692654fea1c60\", \"blink_platform_unittests\": \"196b4a39d095d4193a23667ea609d31e21d2e47a\", \"blink_python_tests\": \"426c7a6b39560276ed6d7c63008e46e590bf8928\", \"blink_unittests\": \"75f18d271feb2cb06e796bf2cf2a9960a3726d8e\", \"blink_web_tests\": \"32f3a8853e31cbb2c6a9daef7c41bf7ed6310dce\", \"boringssl_crypto_tests\": \"662f28c77da22e313dd21fff2f9c9e72530ddcbc\", \"boringssl_ssl_tests\": \"12dab4e0d0514d8722e1e0c45a333ed33f631eb5\", \"browser_tests\": \"ec99bc356bc2400e26efd097f97fc5be6c5dc20e\", \"cacheinvalidation_unittests\": \"ea925d820e993206cc14b83da3a5deed1403a514\", \"capture_unittests\": \"130ec8f09f0ba34f631c7d771111b571261daf2e\", \"cast_unittests\": \"2d0b4f297dc1fea948c678311b33fc0e169aba64\", \"cc_unittests\": \"ebecac4fd9c38a885639525b01d74285382cf70b\", \"chrome_app_unittests\": \"b34f2d6a7b814099a4864f2226be03aa52ecf09c\", \"chromedriver_unittests\": \"c3dcb2ad1ad1c1771418291353a7e816825d24d5\", \"components_browsertests\": \"1ae9865305762f2d4a78358875a526ae8732a107\", \"components_unittests\": \"230a1829aa07c1893efacdb9e49a24aa683da723\", \"compositor_unittests\": \"3108f08cef0a6e2a32af718fb1bed9cebc95b715\", \"content_browsertests\": \"84757e154c006578b40ea769ae3c975716eb7aa4\", \"content_shell_crash_test\": \"27d2d772ecdc9bc24251ba885141bc98d6fc5c08\", \"content_unittests\": \"fcc99eef2508abdb76bd8332c9231860dadbc6ed\", \"crashpad_tests\": \"8b93159af0f865657b0cf56c3fe999af48fab48c\", \"cronet_tests\": \"45254b1fc4f98f7baeb9a04e8def64c7e3d7fe55\", \"cronet_unittests\": \"b9a322550f25536e48df78390743fac6ecfec855\", \"crypto_unittests\": \"a21b71445bd287108995fca793f041e788d15512\", \"dbus_unittests\": \"3ebfe474c3a5d5ae8b2bd37e1debeaf0381c4c19\", \"device_unittests\": \"746434f3919b787340e4dd723e4cc3c43929e4be\", \"display_unittests\": \"d5f1a8e5def4703de25c3763b007e24e2aa8eded\", \"events_unittests\": \"252a6cf72f9e9d80ab0656f1e16d47e0590687e4\", \"extensions_browsertests\": \"a602bc7dbc40600f2c37ae5bcd16e8f6b97c8d6e\", \"extensions_unittests\": \"f11578989b4a3fc3ccb05252a38d6ed17214dc43\", \"filesystem_service_unittests\": \"41298c77f0e2adcb6c541c5e41c06fe30c6ac4a0\", \"flatbuffers_unittests\": \"9eab69acab0f6bd1460710d9ac6e8475de73625c\", \"gcm_unit_tests\": \"3130dffeb46deb0b46972a78dfd7a6dc0fcddf98\", \"gfx_unittests\": \"35941a84833dc93b2977eca9c695da736cc3748d\", \"gin_unittests\": \"2dd3411b199b9da262518258ba6f61393f5fb42f\", \"google_apis_unittests\": \"32b6b2cbad6a26f59688d07b879f98e5fd2395a3\", \"gpu_unittests\": \"dbe3deb4a24f773b853edb5892bd40b10e7f2f95\", \"grit_python_unittests\": \"0a186e54539d3475bae4b9d19cc134d9a92e2108\", \"gwp_asan_unittests\": \"901f0c2458d9fe991f496e3cbf9b23458b4ced9e\", \"headless_browsertests\": \"1de7628e34f4d76bcb9e056e9655188c82b080b4\", \"headless_unittests\": \"2e74a9df7ff0fef5b6f3fd3f170db7ba43f00c6e\", \"interactive_ui_tests\": \"8a2e1ecbce7e233155f1cecfe5a96b818e94ecc1\", \"ipc_tests\": \"d323c4aaaa18fca4afbda6eb7551b41fde82999c\", \"jingle_unittests\": \"8763af3b7d16a514a18e6d21998f58fa1c65da0e\", \"latency_unittests\": \"562e6ea997dbb06e74224a9f9e984343cf53ddbc\", \"libjingle_xmpp_unittests\": \"c551e74d2f8543c7b8771500f39920b81aee6843\", \"media_blink_unittests\": \"50b226a057ea16b0c55b62bc106ea6000be269d9\", \"media_service_unittests\": \"0b07114e8673bc16752b6a678bc4fb9b4d4ee589\", \"media_unittests\": \"3fba7a8af28f00511d74c252ade9ee90daa8bfeb\", \"message_center_unittests\": \"f377c1cff48267778c8e999430711b9037e2c7ef\", \"metrics_python_tests\": \"860332c92fe1d2ade969ea91b0bc79d3f9022d85\", \"midi_unittests\": \"5b4383887e530a7f19e7a8380db9656f0be79a70\", \"mojo_core_unittests\": \"5255db33211bc73caa94d7e68165c84eaacafdf4\", \"mojo_unittests\": \"f30baa96080c37842ac91aee13d3ba8bc3af125d\", \"nacl_helper_nonsfi_unittests\": \"caecb15e17102e25d6ca3108a5e4d9890469bfde\", \"nacl_loader_unittests\": \"33b9626b378f28ccca12902c32be46cc32d4955f\", \"native_theme_unittests\": \"692561b236589d7449dd523b2db25d7701c5609c\", \"net_unittests\": \"6648bdd1248bfa5fadc9a1128569b3fb338d1d11\", \"pdf_unittests\": \"16a13c5c474b22c715009742f7c753e3dede8c53\", \"perfetto_unittests\": \"f8d47249a27ed07286fbdca57690c902df98bdaa\", \"ppapi_unittests\": \"3b57c96f83cf4af52ecb185ceb2894fe11e07a20\", \"printing_unittests\": \"9f50b2f663dd2938a3fedda6a5dfc16f7686ba6b\", \"remoting_unittests\": \"ff0ddd76e91281be7159be0ad91533b231d639d1\", \"sandbox_linux_unittests\": \"ea39847a57442fbc0fba39e0ec10301229d41f74\", \"service_manager_unittests\": \"3cfdd19a42c941e9f117fc227db89a361db14a3d\", \"services_unittests\": \"f6a8ef1817a3427b9417981e7689c3fdf8a97016\", \"shell_dialogs_unittests\": \"ec2b1dac612fb01610a3eb29f484c997e3fcbfb0\", \"skia_unittests\": \"ab72f93d6d6f7c93c842a4df7337d28b3f10e800\", \"snapshot_unittests\": \"cbfa7aa04d812fc68bb1e9883a3963174b93cb46\", \"sql_unittests\": \"e0e35ab6140ad2257bfa0be56dc86f801424a0fe\", \"storage_unittests\": \"c815a91337089c7de840aa475c15d4011c2c1c37\", \"sync_integration_tests\": \"82f0b252ff6a6a8b88664ad974a461939a9f022b\", \"telemetry_gpu_unittests\": \"872faa6a8b5fb232be7dc452e61ea28a641d4d4e\", \"telemetry_perf_unittests\": \"d4c98c52be72f1a7a5f2af0f95f1a2178247d71d\", \"telemetry_unittests\": \"ccffedc76a240ff571b8290ff4a73fdae7cd17d6\", \"traffic_annotation_auditor_unittests\": \"6f4a598e35d97726348c300ca931e80ec7815dbc\", \"ui_base_unittests\": \"0eef50f644815c9caa478bf214e3b0b314da0455\", \"ui_touch_selection_unittests\": \"43ebdbf8413466cc57e467ae76e9cd906c3b3eeb\", \"unit_tests\": \"e7ac15c851ef0eef26e664e9ad58c84de13e702b\", \"url_unittests\": \"3700f29786801439be7cdf86966fb678ad0957bf\", \"views_perftests\": \"f6231895c39261a9a21473d25c7a838b7194a272\", \"views_unittests\": \"d4c2c5a5396b74e5e48034d5454aca5091e5186e\", \"viz_unittests\": \"932c6d0cfd7716c2d1d6bd9dddb272ff12f6bf55\", \"vr_common_unittests\": \"b7f1e92e537e22e64afddc18cce9b69df345092b\", \"vr_pixeltests\": \"dd30252ea67845a809da871b745f5659f353a087\", \"webdriver_wpt_tests\": \"539a122bf76f0c7336e65d8b15612f82ef64b405\", \"weblayer_browsertests\": \"dd64ccfaeee2004c8de09456251fea482fcce3d9\", \"wm_unittests\": \"fe13fec0e73699af00d5ea05d757c586bd70b226\", \"wtf_unittests\": \"6af03931ca7ff498de6eb63a2cb91409e0c0558c\", \"xr_browser_tests\": \"e2fe0275f46d8a6e0b9dc59df3da91462b15fb2c\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_8b7b2365a8f3880d050d5bb210d6d23a3c1fa90e9cf635ebce9fc15f785b82f2_v2\", \"builder_8e7ad9a10a7772feba5b42dda56992e918fe88f23579c3ddaf696dde66ee4238_v2\", \"builder_c4692ee50382bcd35d592bcc0950e009bfc7efa8bba012f560f58cf3ab714e75_v2\", \"builder_d7a16d1002a90b006293eb3aaf9b6ed11d06403f3c58091e1c5077b5d2ff0b40_v2\", \"builder_e21c17032b725bcf1c922976a36352546baf3f39ec148d1e6730a93c0400d8cc_v2\", \"git\", \"goma_v2\", \"vpython\"], \"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-central1-b-8-69-zljv\"], \"image\": [\"chrome-xenial-20012600-8e1a7e485b7\"], \"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\": [\"4825-3d129d6\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1582211959819067",
"status": "COMPLETED",
"status_changed_ts": "1582214830606871",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168827",
"builder:Linux Builder (dbg)",
"buildset:commit/git/501980d9012f2bdc15b1e4ddb5714784c9c936ab",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/501980d9012f2bdc15b1e4ddb5714784c9c936ab",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053354359273627296",
"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/8887924297352146512/+/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:4a7bff14e6bdc410",
"user_agent:luci-scheduler"
],
"updated_ts": "1582214830617981",
"url": "https://ci.chromium.org/b/8887924297352146512",
"utcnow_ts": "1582214831016877"
}
}
[16:07:11.028] Invocation finished in 48m58.583819155s with status SUCCEEDED