[01:39:00.196] New invocation is queued and will start shortly
[01:39:01.428] Starting the invocation (attempt 1)
[01:39:01.480] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:39:01.481] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:39:01.481] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9052862317385453680",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"3db115fc25f61b8ef88adfb0f953dfdca81139f2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9052862317385453680",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/3db115fc25f61b8ef88adfb0f953dfdca81139f2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3db115fc25f61b8ef88adfb0f953dfdca81139f2",
"gitiles_ref:refs/heads/master"
]
}
[01:39:02.142] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1582681141586398",
"id": "8887432255238239184",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3db115fc25f61b8ef88adfb0f953dfdca81139f2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582681142005745",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/169026",
"builder:Linux Builder (dbg)",
"buildset:commit/git/3db115fc25f61b8ef88adfb0f953dfdca81139f2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3db115fc25f61b8ef88adfb0f953dfdca81139f2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9052862317385453680",
"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/8887432255238239184/+/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": "1582681142005863",
"url": "https://ci.chromium.org/b/8887432255238239184",
"utcnow_ts": "1582681142130256"
}
}
[01:39:02.142] Task URL: https://ci.chromium.org/b/8887432255238239184
[01:39:02.142] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:2:0) after 1m0s
[01:40:02.214] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:2:0)
[01:40:02.214] Timer tick, asking Buildbucket for the build status
[01:40:02.366] Build 8887432255238239184: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:40:02.366] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:3:0) after 1m0s
[01:41:02.630] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:3:0)
[01:41:02.630] Timer tick, asking Buildbucket for the build status
[01:41:02.750] Build 8887432255238239184: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:41:02.750] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:4:0) after 1m0s
[01:42:02.844] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:4:0)
[01:42:02.844] Timer tick, asking Buildbucket for the build status
[01:42:03.037] Build 8887432255238239184: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:42:03.037] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:5:0) after 1m0s
[01:43:03.142] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:5:0)
[01:43:03.142] Timer tick, asking Buildbucket for the build status
[01:43:03.812] Build 8887432255238239184: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[01:43:03.812] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:6:0) after 1m0s
[01:44:03.198] Received PubSub notification, asking Buildbucket for the build status
[01:44:03.285] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:03.839] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:6:0)
[01:44:03.839] Timer tick, asking Buildbucket for the build status
[01:44:04.027] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:04.027] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:8:0) after 1m0s
[01:45:04.103] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:8:0)
[01:45:04.103] Timer tick, asking Buildbucket for the build status
[01:45:04.426] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:04.426] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:9:0) after 1m0s
[01:46:04.501] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:9:0)
[01:46:04.501] Timer tick, asking Buildbucket for the build status
[01:46:04.614] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:04.614] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:10:0) after 1m0s
[01:47:04.692] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:10:0)
[01:47:04.692] Timer tick, asking Buildbucket for the build status
[01:47:04.856] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:04.856] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:11:0) after 1m0s
[01:48:04.864] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:11:0)
[01:48:04.864] Timer tick, asking Buildbucket for the build status
[01:48:04.999] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:04.999] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:12:0) after 1m0s
[01:49:05.060] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:12:0)
[01:49:05.060] Timer tick, asking Buildbucket for the build status
[01:49:05.167] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:05.167] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:13:0) after 1m0s
[01:50:05.323] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:13:0)
[01:50:05.323] Timer tick, asking Buildbucket for the build status
[01:50:05.471] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:05.471] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:14:0) after 1m0s
[01:51:05.574] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:14:0)
[01:51:05.574] Timer tick, asking Buildbucket for the build status
[01:51:05.667] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:05.667] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:15:0) after 1m0s
[01:52:05.725] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:15:0)
[01:52:05.725] Timer tick, asking Buildbucket for the build status
[01:52:05.827] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:05.827] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:16:0) after 1m0s
[01:53:05.922] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:16:0)
[01:53:05.922] Timer tick, asking Buildbucket for the build status
[01:53:06.022] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:06.022] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:17:0) after 1m0s
[01:54:06.077] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:17:0)
[01:54:06.077] Timer tick, asking Buildbucket for the build status
[01:54:06.222] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:06.222] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:18:0) after 1m0s
[01:55:06.280] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:18:0)
[01:55:06.280] Timer tick, asking Buildbucket for the build status
[01:55:06.444] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:06.445] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:19:0) after 1m0s
[01:56:06.626] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:19:0)
[01:56:06.626] Timer tick, asking Buildbucket for the build status
[01:56:06.964] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:06.964] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:20:0) after 1m0s
[01:57:07.065] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:20:0)
[01:57:07.065] Timer tick, asking Buildbucket for the build status
[01:57:07.248] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:07.248] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:21:0) after 1m0s
[01:58:07.376] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:21:0)
[01:58:07.376] Timer tick, asking Buildbucket for the build status
[01:58:07.512] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:07.512] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:22:0) after 1m0s
[01:59:07.569] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:22:0)
[01:59:07.569] Timer tick, asking Buildbucket for the build status
[01:59:07.804] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:07.804] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:23:0) after 1m0s
[02:00:07.905] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:23:0)
[02:00:07.905] Timer tick, asking Buildbucket for the build status
[02:00:08.301] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:08.301] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:24:0) after 1m0s
[02:01:08.424] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:24:0)
[02:01:08.424] Timer tick, asking Buildbucket for the build status
[02:01:08.569] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:08.569] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:25:0) after 1m0s
[02:02:08.684] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:25:0)
[02:02:08.684] Timer tick, asking Buildbucket for the build status
[02:02:09.031] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:09.031] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:26:0) after 1m0s
[02:03:09.156] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:26:0)
[02:03:09.156] Timer tick, asking Buildbucket for the build status
[02:03:09.646] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:09.646] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:27:0) after 1m0s
[02:04:09.882] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:27:0)
[02:04:09.882] Timer tick, asking Buildbucket for the build status
[02:04:10.056] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:10.057] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:28:0) after 1m0s
[02:05:10.103] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:28:0)
[02:05:10.103] Timer tick, asking Buildbucket for the build status
[02:05:10.237] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:10.237] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:29:0) after 1m0s
[02:06:10.391] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:29:0)
[02:06:10.391] Timer tick, asking Buildbucket for the build status
[02:06:10.554] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:10.554] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:30:0) after 1m0s
[02:07:10.554] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:30:0)
[02:07:10.554] Timer tick, asking Buildbucket for the build status
[02:07:10.894] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:10.894] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:31:0) after 1m0s
[02:08:10.994] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:31:0)
[02:08:10.994] Timer tick, asking Buildbucket for the build status
[02:08:11.234] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:11.234] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:32:0) after 1m0s
[02:09:11.350] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:32:0)
[02:09:11.350] Timer tick, asking Buildbucket for the build status
[02:09:11.498] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:11.498] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:33:0) after 1m0s
[02:10:11.493] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:33:0)
[02:10:11.493] Timer tick, asking Buildbucket for the build status
[02:10:11.587] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:11.587] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:34:0) after 1m0s
[02:11:11.611] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:34:0)
[02:11:11.611] Timer tick, asking Buildbucket for the build status
[02:11:11.768] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:11.768] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:35:0) after 1m0s
[02:12:11.810] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:35:0)
[02:12:11.810] Timer tick, asking Buildbucket for the build status
[02:12:12.010] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:12.010] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:36:0) after 1m0s
[02:13:12.118] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:36:0)
[02:13:12.118] Timer tick, asking Buildbucket for the build status
[02:13:12.562] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:12.562] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:37:0) after 1m0s
[02:14:12.621] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:37:0)
[02:14:12.621] Timer tick, asking Buildbucket for the build status
[02:14:13.006] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:13.006] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:38:0) after 1m0s
[02:15:13.102] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:38:0)
[02:15:13.102] Timer tick, asking Buildbucket for the build status
[02:15:13.284] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:13.284] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:39:0) after 1m0s
[02:16:13.305] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:39:0)
[02:16:13.305] Timer tick, asking Buildbucket for the build status
[02:16:13.405] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:13.405] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:40:0) after 1m0s
[02:17:13.467] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:40:0)
[02:17:13.467] Timer tick, asking Buildbucket for the build status
[02:17:14.951] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:14.951] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:41:0) after 1m0s
[02:18:15.879] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:41:0)
[02:18:15.879] Timer tick, asking Buildbucket for the build status
[02:18:16.082] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:16.082] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:42:0) after 1m0s
[02:19:16.537] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:42:0)
[02:19:16.537] Timer tick, asking Buildbucket for the build status
[02:19:16.863] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:16.863] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:43:0) after 1m0s
[02:20:17.008] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:43:0)
[02:20:17.008] Timer tick, asking Buildbucket for the build status
[02:20:17.322] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:17.322] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:44:0) after 1m0s
[02:21:17.467] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:44:0)
[02:21:17.467] Timer tick, asking Buildbucket for the build status
[02:21:17.788] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:17.788] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:45:0) after 1m0s
[02:22:17.872] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:45:0)
[02:22:17.872] Timer tick, asking Buildbucket for the build status
[02:22:18.058] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:18.058] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:46:0) after 1m0s
[02:23:18.114] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:46:0)
[02:23:18.114] Timer tick, asking Buildbucket for the build status
[02:23:18.325] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:18.325] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:47:0) after 1m0s
[02:24:18.511] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:47:0)
[02:24:18.511] Timer tick, asking Buildbucket for the build status
[02:24:18.623] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:18.623] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:48:0) after 1m0s
[02:25:18.734] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:48:0)
[02:25:18.734] Timer tick, asking Buildbucket for the build status
[02:25:18.835] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:18.835] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:49:0) after 1m0s
[02:26:19.047] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:49:0)
[02:26:19.047] Timer tick, asking Buildbucket for the build status
[02:26:19.197] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:19.197] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:50:0) after 1m0s
[02:27:19.239] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:50:0)
[02:27:19.239] Timer tick, asking Buildbucket for the build status
[02:27:19.514] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:19.514] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:51:0) after 1m0s
[02:28:19.675] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:51:0)
[02:28:19.675] Timer tick, asking Buildbucket for the build status
[02:28:20.122] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:20.122] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:52:0) after 1m0s
[02:29:20.278] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:52:0)
[02:29:20.278] Timer tick, asking Buildbucket for the build status
[02:29:21.044] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:21.044] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:53:0) after 1m0s
[02:30:21.277] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:53:0)
[02:30:21.277] Timer tick, asking Buildbucket for the build status
[02:30:21.421] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:21.421] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:54:0) after 1m0s
[02:31:21.562] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:54:0)
[02:31:21.562] Timer tick, asking Buildbucket for the build status
[02:31:22.093] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:22.093] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:55:0) after 1m0s
[02:32:22.272] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:55:0)
[02:32:22.272] Timer tick, asking Buildbucket for the build status
[02:32:22.462] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:22.462] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:56:0) after 1m0s
[02:33:22.649] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:56:0)
[02:33:22.649] Timer tick, asking Buildbucket for the build status
[02:33:22.932] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:22.932] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:57:0) after 1m0s
[02:34:23.188] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:57:0)
[02:34:23.188] Timer tick, asking Buildbucket for the build status
[02:34:23.414] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:23.414] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:58:0) after 1m0s
[02:35:23.474] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:58:0)
[02:35:23.474] Timer tick, asking Buildbucket for the build status
[02:35:23.611] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:23.611] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:59:0) after 1m0s
[02:36:23.749] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:59:0)
[02:36:23.749] Timer tick, asking Buildbucket for the build status
[02:36:23.908] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:23.908] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:60:0) after 1m0s
[02:37:23.978] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:60:0)
[02:37:23.978] Timer tick, asking Buildbucket for the build status
[02:37:24.145] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:24.146] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:61:0) after 1m0s
[02:38:24.277] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:61:0)
[02:38:24.277] Timer tick, asking Buildbucket for the build status
[02:38:24.468] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:24.468] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:62:0) after 1m0s
[02:39:24.505] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:62:0)
[02:39:24.506] Timer tick, asking Buildbucket for the build status
[02:39:24.890] Build 8887432255238239184: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:24.890] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9052862317385453680:63:0) after 1m0s
[02:39:56.963] Received PubSub notification, asking Buildbucket for the build status
[02:39:57.867] Build 8887432255238239184: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:39:57.867] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1582684796259309",
"created_by": "project:chromium",
"created_ts": "1582681141586398",
"id": "8887432255238239184",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3db115fc25f61b8ef88adfb0f953dfdca81139f2\"}}",
"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-66-7yoe\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 169026, \"got_angle_revision\": \"27cb806ca81951b702740f3cb3a5193a9135e65b\", \"got_dawn_revision\": \"227568451b442ae16873ec23cd4b86cf28d00b2a\", \"got_nacl_revision\": \"e2b3df265ebed5bccb0f5f670494bc800e7e3c1a\", \"got_revision\": \"3db115fc25f61b8ef88adfb0f953dfdca81139f2\", \"got_revision_cp\": \"refs/heads/master@{#744491}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"da659b3741132fe6a6904e45d96112eef776bc28\", \"got_v8_revision\": \"57083a22efbe63d75af8adb979583fa3aa0ff0d4\", \"got_v8_revision_cp\": \"refs/heads/8.2.173@{#1}\", \"got_webrtc_revision\": \"422f9dd5df8e70ecca1e0aff83b0e2e114bc70aa\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30612}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3db115fc25f61b8ef88adfb0f953dfdca81139f2\", \"swarm_hashes_refs/heads/master(at){#744491}_without_patch\": {\"accessibility_unittests\": \"56bc38ede9b493dbe71b90323709a61c01b3db89\", \"angle_unittests\": \"f338b38085ed3cf9674fb76c10fa643846c88dcf\", \"app_shell_unittests\": \"7a588ecdb3b84b628593dc2225ac945228d0ffbb\", \"aura_unittests\": \"3a2dc1354d9ceb3a7104e891591574fbb4db6231\", \"base_unittests\": \"1e4c2b7aa313abc19f4e9d7187fbfbb81c627492\", \"base_util_unittests\": \"f2a3e5fe02659a290668e9eddbfa56387c9496c4\", \"blink_common_unittests\": \"5dfec48d57722de610cfddc1d11278ddcdf007de\", \"blink_fuzzer_unittests\": \"dfbbd7f4d2db2bf408de667bc0d16cf4584d062b\", \"blink_heap_unittests\": \"f95c4d39b54da691b975caea61f2d9e47ee394b0\", \"blink_platform_unittests\": \"e75051f4cd0d6faf7d0a31c2a3ce0da7db316020\", \"blink_python_tests\": \"d276654e7e588fb19ab307b459156c95ce85dc30\", \"blink_unittests\": \"892ef9111da3dd8f9c7b6ee472d9eb42686d2f74\", \"blink_web_tests\": \"12b97910cb8a678d17ae0c451e52c7dca65f2c9d\", \"boringssl_crypto_tests\": \"645c02bf39dae19547f5513dc91cf3121f370bc5\", \"boringssl_ssl_tests\": \"680b545f58a53d5f14df6792969b6bf4226f2cd6\", \"browser_tests\": \"6dce1fc4d64119cb8a33b05ee07071a631af14e6\", \"cacheinvalidation_unittests\": \"0340a0ffea3aa6050f1283cc35b9bae0ae565951\", \"capture_unittests\": \"721b57d6f2bfef261b029815e70b18575463d85a\", \"cast_unittests\": \"7bc3c094d54f6f39997653fabfd8ff004427be4a\", \"cc_unittests\": \"08786e5938748691113494c3b414718129b4b127\", \"chrome_app_unittests\": \"badf10dca3e2d10f4f4cdd444fce9ee14280f52a\", \"chromedriver_unittests\": \"57b1282dc9b8325a3062e9ab28d9733a40a8194d\", \"components_browsertests\": \"028acb46252449d3f2052cd09b3ff08ba392fd37\", \"components_unittests\": \"ab00bdc34d544d04430c50c3e364fb88b7e3116d\", \"compositor_unittests\": \"a476ea00a75fd7bd693dedf44a2798ae58d506e2\", \"content_browsertests\": \"ac511c6191d4806849cbe61707b00def261baec6\", \"content_shell_crash_test\": \"b3d5cca3379b84b7b206b4f4ee75972aa39a077a\", \"content_unittests\": \"ef261952a2d254270eb2eaf659a46a391c754886\", \"crashpad_tests\": \"52335fcbe2846a4f04c4973574ff2c35ba7695f4\", \"cronet_tests\": \"8182c3a7c9f5618aeb02246076cd2c2282ed6960\", \"cronet_unittests\": \"5ed0016a290004c9d7f4ba534b6aa5b803d666e6\", \"crypto_unittests\": \"4209ffb1829140412c01b5fb5dfc9db66cdc5a64\", \"dbus_unittests\": \"a1faf5babb02551015ccba38832091bca700a399\", \"device_unittests\": \"27b338ebf1a06145e2fce72d7dbc528a7f525887\", \"display_unittests\": \"fe883729979344c4bb51812b1760b6ecd5c4bfb3\", \"events_unittests\": \"f15ad9644e2c19e4f5211968c091a7366ec1f366\", \"extensions_browsertests\": \"16fa92cdd8370680f0c8e7a8a6d5048c4acc9d7b\", \"extensions_unittests\": \"d43b8c33a77ecfd282a3f3acba4eaadee88badf2\", \"filesystem_service_unittests\": \"808deb351fb3bd88db7c537dfcfc4f758e58a49c\", \"flatbuffers_unittests\": \"fddfee5d9c5c5357203fa42dff8f3c357607bc22\", \"gcm_unit_tests\": \"1c054f75f674592dda501edbd8be080f29981679\", \"gfx_unittests\": \"cc654fda5fad64e4d526fcbdc646b063cc16bcbc\", \"gin_unittests\": \"f85f930f4c7e2700709157f4e7fd65bbaa841aa9\", \"google_apis_unittests\": \"2d054ce61d2fc38cd46ee60e6975f42795a8ba76\", \"gpu_unittests\": \"08d6d1c79d53dcaaf54bca7534ac6c1605133091\", \"grit_python_unittests\": \"8d3af1ca6ce7611b3ef6d2516a78cd61f915c28b\", \"gwp_asan_unittests\": \"f9931a1c16bf0385d9a1e68694ebb3efa12b0be6\", \"headless_browsertests\": \"c249af4e0e4e2ac4894c809ba719498c17732794\", \"headless_unittests\": \"0b244e1540aced667e8379dad3ba0a6f824163b6\", \"interactive_ui_tests\": \"2a24e11620216ed9dd6839e4b73aacd42ee955ac\", \"ipc_tests\": \"9fc0e8262c9515f2f90026e87294f31fb169c81c\", \"jingle_unittests\": \"f1861f72a3afbb79e9df898b7d641e4150ba6258\", \"latency_unittests\": \"efedc844a6fd19d94a05e62458b6d323dfafec61\", \"libjingle_xmpp_unittests\": \"6085ff384be45b440c3de0167a72f0e61ac5d97a\", \"media_blink_unittests\": \"fe35899a878394ee300e393e259cb10096a12adb\", \"media_service_unittests\": \"47adb320200e575caf962127165c037ab7658000\", \"media_unittests\": \"457e25442386ed1080254bd056410c48374d8112\", \"message_center_unittests\": \"6db2386c165f4b207577a5702fdba47f46aa3a4e\", \"metrics_python_tests\": \"b5f11165c6a1599ef53c7627e6be78e5b0f93b49\", \"midi_unittests\": \"ea3b618a64212a0875e21f9de3d5e42bbc7e3350\", \"mojo_core_unittests\": \"1bb1e648ef92fa8977968ad2744e53982c4ec9b6\", \"mojo_unittests\": \"b6540142a5033a79a3269206478fd2a282418ebd\", \"nacl_helper_nonsfi_unittests\": \"f2e81d6cb41bb06d2bdca4a2ed0d77f22a887857\", \"nacl_loader_unittests\": \"6c434a9599c962aabc4f6e0078afcfb8079988ba\", \"native_theme_unittests\": \"d7be906c6f727cfeef8294ba8ce0c8dd9c50ab1f\", \"net_unittests\": \"f18992e58b06a316d3e90495502cd84ae43c6f6b\", \"pdf_unittests\": \"84bed2e5a0549e531c1818ba49d106e911c4887f\", \"perfetto_unittests\": \"a342937254cacf69269a4fc2c69c97c27ac25a92\", \"ppapi_unittests\": \"50dea513cd7de955fcf326990ff330713ffda22b\", \"printing_unittests\": \"ff13b281d95164ee6a502507ab49177789742e4a\", \"remoting_unittests\": \"cc2e53553a8a947e682fabaae843e3cc6be31eff\", \"sandbox_linux_unittests\": \"982bef5f630b96d488bb8d52d6c3a0af9d30a657\", \"service_manager_unittests\": \"67072abaf71212b471f00b9ff6bd185e07c45459\", \"services_unittests\": \"959acec1c7892911f9fb29290b88643cbc1fbdad\", \"shell_dialogs_unittests\": \"7ca45a1575581dd2582076579e01f53bcd224cad\", \"skia_unittests\": \"c150eb5d41ae5ca231688e21a42a3d7ad4325604\", \"snapshot_unittests\": \"0afad99faadea120e0ed219d117de354e14b70fa\", \"sql_unittests\": \"3e3bc20e2d831bae044cd01f0f80041320572d1e\", \"storage_unittests\": \"a51b7462cf704c82407255275a26cdec6db511f8\", \"sync_integration_tests\": \"dc92340fc6290ff69eb477aa798f2dc6e678304f\", \"telemetry_gpu_unittests\": \"a74a7906a10f8b7c1929d701fe463f012de6fd62\", \"telemetry_perf_unittests\": \"069711414355ee5f90a1a7786480fba9903e64b7\", \"telemetry_unittests\": \"69a2541326dd257c9ae0adb5ae1fc40cb2ab2c6a\", \"traffic_annotation_auditor_unittests\": \"7bac54b8d589cf726834e4c483137365f97bf2dd\", \"ui_base_unittests\": \"2dafbb1cd95272f4eadfe07de772783a05982c4f\", \"ui_touch_selection_unittests\": \"50c782b8fee58af6caddcf58f8cbe34953320956\", \"unit_tests\": \"6833624fb1f793cb57cebda23a8787b15c7de04f\", \"url_unittests\": \"ab296c42a08b0eb54c68094786edc60c14a67b20\", \"views_perftests\": \"82524e1d440763c7f747de0e66123b41a88a8670\", \"views_unittests\": \"7e825f503bb77c53138bbbb0e0f0c268f8968be8\", \"viz_unittests\": \"994d53df20daec21a085a76aeb2f53e03bd07d51\", \"vr_common_unittests\": \"e8c510833994424bd43008cb8fd92c3950bb1d3a\", \"vr_pixeltests\": \"d5bdc8b91ce35f997b9cc6beb0b1825cdb01fdbf\", \"webdriver_wpt_tests\": \"caef8cd74949e5339ba3865c5c2ec674256eae6a\", \"weblayer_browsertests\": \"47d5a278426fb814c5d46d95317068660be494e8\", \"wm_unittests\": \"3aea3f5046b222f7bf4bc9158e94f63a06a31bc7\", \"wtf_unittests\": \"6bffdeb5bfa523f4cba826a2ca87005a57208d0f\", \"xr_browser_tests\": \"6820b2534ad1bd5256a825200827b7fbbfa2dc3a\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_756d7885c4e2fdf98a0ecf3a0240e3f6188de5be53188a09baa9eae15dedd66f_v2\", \"builder_7d75f6999c07e42a7f7fde2313383264fbd8ed871e2008bde33b278973c1f4c8_v2\", \"builder_bee64b857be6df8b7c1ab34ae7b2c6eab296c35495667b8b8424b2083d103b1e_v2\", \"builder_c4692ee50382bcd35d592bcc0950e009bfc7efa8bba012f560f58cf3ab714e75_v2\", \"builder_dd987fcd94b516d81fcf1552cd73a9eed370c3dc02cc1a52ddee828f1ab8817d_v2\", \"builder_f72b88ee7914fe5eb8b32d979ad6b47003d3a0322a10970d43d78c86a32dd14e_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-east1-d-8-66-7yoe\"], \"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-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1582681442173585",
"status": "COMPLETED",
"status_changed_ts": "1582684796561171",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/169026",
"builder:Linux Builder (dbg)",
"buildset:commit/git/3db115fc25f61b8ef88adfb0f953dfdca81139f2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3db115fc25f61b8ef88adfb0f953dfdca81139f2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9052862317385453680",
"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/8887432255238239184/+/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:4a97f73bc6297c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1582684796582474",
"url": "https://ci.chromium.org/b/8887432255238239184",
"utcnow_ts": "1582684797131377"
}
}
[02:39:57.867] Invocation finished in 1h0m57.712219533s with status SUCCEEDED