[23:48:37.203] New invocation is queued and will start shortly
[23:48:38.943] Starting the invocation (attempt 1)
[23:48:39.019] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:48:39.019] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:48:39.019] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9054681201397391792",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5068d53694c51192af3b7b7518477ceeff52df02\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9054681201397391792",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/5068d53694c51192af3b7b7518477ceeff52df02",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5068d53694c51192af3b7b7518477ceeff52df02",
"gitiles_ref:refs/heads/master"
]
}
[23:48:39.729] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1580946519157974",
"id": "8889251138686860032",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5068d53694c51192af3b7b7518477ceeff52df02\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1580946519579378",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168275",
"builder:Linux Builder (dbg)",
"buildset:commit/git/5068d53694c51192af3b7b7518477ceeff52df02",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5068d53694c51192af3b7b7518477ceeff52df02",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054681201397391792",
"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/8889251138686860032/+/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": "1580946519579484",
"url": "https://ci.chromium.org/b/8889251138686860032",
"utcnow_ts": "1580946519719791"
}
}
[23:48:39.729] Task URL: https://ci.chromium.org/b/8889251138686860032
[23:48:39.729] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:2:0) after 1m0s
[23:49:39.736] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:2:0)
[23:49:39.736] Timer tick, asking Buildbucket for the build status
[23:49:40.221] Build 8889251138686860032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:49:40.221] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:3:0) after 1m0s
[23:50:40.269] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:3:0)
[23:50:40.269] Timer tick, asking Buildbucket for the build status
[23:50:40.998] Build 8889251138686860032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:50:40.998] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:4:0) after 1m0s
[23:51:41.062] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:4:0)
[23:51:41.062] Timer tick, asking Buildbucket for the build status
[23:51:41.255] Build 8889251138686860032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:51:41.255] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:5:0) after 1m0s
[23:52:41.295] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:5:0)
[23:52:41.295] Timer tick, asking Buildbucket for the build status
[23:52:41.453] Build 8889251138686860032: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:52:41.453] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:6:0) after 1m0s
[23:53:14.399] Received PubSub notification, asking Buildbucket for the build status
[23:53:14.617] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:41.525] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:6:0)
[23:53:41.525] Timer tick, asking Buildbucket for the build status
[23:53:41.803] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:41.803] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:8:0) after 1m0s
[23:54:41.800] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:8:0)
[23:54:41.800] Timer tick, asking Buildbucket for the build status
[23:54:41.976] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:41.976] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:9:0) after 1m0s
[23:55:42.042] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:9:0)
[23:55:42.042] Timer tick, asking Buildbucket for the build status
[23:55:42.322] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:42.322] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:10:0) after 1m0s
[23:56:42.357] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:10:0)
[23:56:42.357] Timer tick, asking Buildbucket for the build status
[23:56:42.528] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:42.528] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:11:0) after 1m0s
[23:57:42.619] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:11:0)
[23:57:42.619] Timer tick, asking Buildbucket for the build status
[23:57:42.788] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:42.788] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:12:0) after 1m0s
[23:58:43.831] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:12:0)
[23:58:43.831] Timer tick, asking Buildbucket for the build status
[23:58:44.034] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:44.034] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:13:0) after 1m0s
[23:59:44.283] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:13:0)
[23:59:44.283] Timer tick, asking Buildbucket for the build status
[23:59:44.798] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:44.798] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:14:0) after 1m0s
[00:00:44.830] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:14:0)
[00:00:44.830] Timer tick, asking Buildbucket for the build status
[00:00:45.445] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:45.445] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:15:0) after 1m0s
[00:01:45.703] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:15:0)
[00:01:45.703] Timer tick, asking Buildbucket for the build status
[00:01:46.117] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:46.117] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:16:0) after 1m0s
[00:02:46.622] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:16:0)
[00:02:46.622] Timer tick, asking Buildbucket for the build status
[00:02:47.294] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:47.294] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:17:0) after 1m0s
[00:03:47.510] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:17:0)
[00:03:47.510] Timer tick, asking Buildbucket for the build status
[00:03:47.730] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:47.730] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:18:0) after 1m0s
[00:04:48.037] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:18:0)
[00:04:48.037] Timer tick, asking Buildbucket for the build status
[00:04:48.931] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:48.931] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:19:0) after 1m0s
[00:05:49.144] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:19:0)
[00:05:49.144] Timer tick, asking Buildbucket for the build status
[00:05:49.843] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:49.843] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:20:0) after 1m0s
[00:06:50.044] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:20:0)
[00:06:50.044] Timer tick, asking Buildbucket for the build status
[00:06:52.965] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:52.965] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:21:0) after 1m0s
[00:07:53.102] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:21:0)
[00:07:53.102] Timer tick, asking Buildbucket for the build status
[00:07:53.410] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:53.410] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:22:0) after 1m0s
[00:08:53.660] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:22:0)
[00:08:53.660] Timer tick, asking Buildbucket for the build status
[00:08:53.832] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:53.832] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:23:0) after 1m0s
[00:09:53.853] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:23:0)
[00:09:53.853] Timer tick, asking Buildbucket for the build status
[00:09:54.484] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:54.484] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:24:0) after 1m0s
[00:10:54.637] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:24:0)
[00:10:54.637] Timer tick, asking Buildbucket for the build status
[00:10:54.840] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:54.840] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:25:0) after 1m0s
[00:11:54.896] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:25:0)
[00:11:54.896] Timer tick, asking Buildbucket for the build status
[00:11:55.256] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:55.256] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:26:0) after 1m0s
[00:12:55.326] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:26:0)
[00:12:55.326] Timer tick, asking Buildbucket for the build status
[00:12:55.717] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:55.717] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:27:0) after 1m0s
[00:13:55.736] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:27:0)
[00:13:55.736] Timer tick, asking Buildbucket for the build status
[00:13:55.966] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:55.966] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:28:0) after 1m0s
[00:14:56.061] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:28:0)
[00:14:56.061] Timer tick, asking Buildbucket for the build status
[00:14:56.237] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:56.237] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:29:0) after 1m0s
[00:15:56.320] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:29:0)
[00:15:56.320] Timer tick, asking Buildbucket for the build status
[00:15:57.446] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:57.446] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:30:0) after 1m0s
[00:16:58.012] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:30:0)
[00:16:58.012] Timer tick, asking Buildbucket for the build status
[00:16:58.210] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:58.210] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:31:0) after 1m0s
[00:17:58.411] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:31:0)
[00:17:58.411] Timer tick, asking Buildbucket for the build status
[00:17:58.585] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:58.585] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:32:0) after 1m0s
[00:18:58.601] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:32:0)
[00:18:58.601] Timer tick, asking Buildbucket for the build status
[00:18:58.807] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:58.807] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:33:0) after 1m0s
[00:19:58.892] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:33:0)
[00:19:58.892] Timer tick, asking Buildbucket for the build status
[00:19:59.038] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:59.038] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:34:0) after 1m0s
[00:20:59.165] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:34:0)
[00:20:59.165] Timer tick, asking Buildbucket for the build status
[00:20:59.430] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:59.430] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:35:0) after 1m0s
[00:21:59.435] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:35:0)
[00:21:59.435] Timer tick, asking Buildbucket for the build status
[00:21:59.606] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:59.606] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:36:0) after 1m0s
[00:22:59.723] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:36:0)
[00:22:59.723] Timer tick, asking Buildbucket for the build status
[00:23:00.857] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:00.857] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:37:0) after 1m0s
[00:24:00.843] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:37:0)
[00:24:00.844] Timer tick, asking Buildbucket for the build status
[00:24:01.134] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:01.134] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:38:0) after 1m0s
[00:25:01.119] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:38:0)
[00:25:01.119] Timer tick, asking Buildbucket for the build status
[00:25:01.459] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:01.459] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:39:0) after 1m0s
[00:26:01.508] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:39:0)
[00:26:01.508] Timer tick, asking Buildbucket for the build status
[00:26:02.017] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:02.017] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:40:0) after 1m0s
[00:27:02.075] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:40:0)
[00:27:02.075] Timer tick, asking Buildbucket for the build status
[00:27:02.291] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:02.291] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:41:0) after 1m0s
[00:28:02.302] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:41:0)
[00:28:02.302] Timer tick, asking Buildbucket for the build status
[00:28:02.502] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:02.502] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:42:0) after 1m0s
[00:29:02.564] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:42:0)
[00:29:02.564] Timer tick, asking Buildbucket for the build status
[00:29:02.763] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:02.763] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:43:0) after 1m0s
[00:30:02.777] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:43:0)
[00:30:02.778] Timer tick, asking Buildbucket for the build status
[00:30:02.917] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:02.917] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:44:0) after 1m0s
[00:31:03.033] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:44:0)
[00:31:03.033] Timer tick, asking Buildbucket for the build status
[00:31:03.337] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:03.337] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:45:0) after 1m0s
[00:32:03.557] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:45:0)
[00:32:03.557] Timer tick, asking Buildbucket for the build status
[00:32:03.784] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:03.784] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:46:0) after 1m0s
[00:33:03.936] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:46:0)
[00:33:03.936] Timer tick, asking Buildbucket for the build status
[00:33:04.251] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:04.251] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:47:0) after 1m0s
[00:34:04.434] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:47:0)
[00:34:04.434] Timer tick, asking Buildbucket for the build status
[00:34:05.059] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:05.059] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:48:0) after 1m0s
[00:35:05.113] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:48:0)
[00:35:05.113] Timer tick, asking Buildbucket for the build status
[00:35:05.351] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:05.351] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:49:0) after 1m0s
[00:36:05.364] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:49:0)
[00:36:05.364] Timer tick, asking Buildbucket for the build status
[00:36:05.555] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:05.555] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:50:0) after 1m0s
[00:37:05.583] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:50:0)
[00:37:05.583] Timer tick, asking Buildbucket for the build status
[00:37:05.840] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:05.840] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:51:0) after 1m0s
[00:38:05.916] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:51:0)
[00:38:05.916] Timer tick, asking Buildbucket for the build status
[00:38:06.130] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:06.130] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:52:0) after 1m0s
[00:39:06.412] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:52:0)
[00:39:06.412] Timer tick, asking Buildbucket for the build status
[00:39:06.892] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:06.892] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:53:0) after 1m0s
[00:40:06.912] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:53:0)
[00:40:06.912] Timer tick, asking Buildbucket for the build status
[00:40:07.282] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:07.283] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:54:0) after 1m0s
[00:41:07.386] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:54:0)
[00:41:07.386] Timer tick, asking Buildbucket for the build status
[00:41:07.597] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:07.597] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:55:0) after 1m0s
[00:42:07.709] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:55:0)
[00:42:07.709] Timer tick, asking Buildbucket for the build status
[00:42:08.992] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:08.992] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:56:0) after 1m0s
[00:43:09.086] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:56:0)
[00:43:09.086] Timer tick, asking Buildbucket for the build status
[00:43:09.333] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:09.333] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:57:0) after 1m0s
[00:44:09.408] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:57:0)
[00:44:09.408] Timer tick, asking Buildbucket for the build status
[00:44:10.008] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:10.008] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:58:0) after 1m0s
[00:45:10.115] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:58:0)
[00:45:10.115] Timer tick, asking Buildbucket for the build status
[00:45:10.366] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:10.366] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:59:0) after 1m0s
[00:46:10.422] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:59:0)
[00:46:10.422] Timer tick, asking Buildbucket for the build status
[00:46:10.812] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:10.812] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:60:0) after 1m0s
[00:47:10.846] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:60:0)
[00:47:10.846] Timer tick, asking Buildbucket for the build status
[00:47:11.848] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:11.848] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:61:0) after 1m0s
[00:48:11.912] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:61:0)
[00:48:11.912] Timer tick, asking Buildbucket for the build status
[00:48:12.098] Build 8889251138686860032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:12.098] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054681201397391792:62:0) after 1m0s
[00:48:20.551] Received PubSub notification, asking Buildbucket for the build status
[00:48:20.875] Build 8889251138686860032: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:48:20.875] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1580950099674357",
"created_by": "project:chromium",
"created_ts": "1580946519157974",
"id": "8889251138686860032",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5068d53694c51192af3b7b7518477ceeff52df02\"}}",
"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-13-jmt6\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168275, \"got_angle_revision\": \"a44d96dcd676fc7d446b41fb766caad9bdab9fda\", \"got_dawn_revision\": \"7fe6efba4ae3388b0cc858b95b4e3755191fc942\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"5068d53694c51192af3b7b7518477ceeff52df02\", \"got_revision_cp\": \"refs/heads/master@{#738735}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"dd48b7e2d06e74e1d20da5f43224b4ae99873e64\", \"got_v8_revision\": \"4dc7c627a1ebc90c34cdb39c44015a83d924f4ee\", \"got_v8_revision_cp\": \"refs/heads/8.2.12@{#1}\", \"got_webrtc_revision\": \"703a5d76d9ba8e7984509cc7bf70fb4ed84ef6be\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30461}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5068d53694c51192af3b7b7518477ceeff52df02\", \"swarm_hashes_refs/heads/master(at){#738735}_without_patch\": {\"accessibility_unittests\": \"621e48bd8a30be63e7823de3a5e9de66bfa211b6\", \"angle_unittests\": \"d2b20d8c30fef31c1c2395ed06855c9a5922f5ff\", \"app_shell_unittests\": \"c6819fff3de17e80081f417cb856c8b95efe8611\", \"aura_unittests\": \"0292f4505d82cd77255d60015d6f8a98b032ebc5\", \"base_unittests\": \"db6689ca3cd271159268dad6356854651e9165d4\", \"base_util_unittests\": \"1fb24bf247e4c9ff5abdd7750c9e5d4ba74014d0\", \"blink_common_unittests\": \"e532c8935da1da6421d452fce8dde3fa6dad6ed8\", \"blink_fuzzer_unittests\": \"1e7831cbf310b9ae658d8bdd4854534bcf7597c4\", \"blink_heap_unittests\": \"f26084cd7f62a934e59fb68f979705827aadadad\", \"blink_platform_unittests\": \"d7f87a7035d5365c50d4747a0d8c93b6454e9a6c\", \"blink_python_tests\": \"ab68f28bb8f8a88ac25d3cd36b90a67a54bac2b1\", \"blink_unittests\": \"9de11ebd2ad98b6453629c68cfa8567a5beaf0ba\", \"blink_web_tests\": \"5901fd8f0b3ad87ed10311adb03f894657f38917\", \"boringssl_crypto_tests\": \"59ffb3dea1f049cbd1273457caef906771add2ec\", \"boringssl_ssl_tests\": \"9d93285a4117d25760a190a2546ea756631a04f4\", \"browser_tests\": \"fac4af00f542ca7f43873e58bd54bf2dc3169e7e\", \"cacheinvalidation_unittests\": \"6380104f37c3571d0d5b29db58ed9a95e25d4918\", \"capture_unittests\": \"80da892393e6b591c2acf3c01c0e044042cd69ec\", \"cast_unittests\": \"e465957c822a703df0f96fe5a7d8873d08be181f\", \"cc_unittests\": \"a1274521e736bcce23a9cfe633415671ee030e52\", \"chrome_app_unittests\": \"72ab555cd2f61884dcfe95555a00a79e3ed835e3\", \"chromedriver_unittests\": \"b95fe9c5cbaa664ce5c8768444259ef9d17fdf56\", \"components_browsertests\": \"a2a21325f321ea4939fdd4efe301db2910169ba9\", \"components_unittests\": \"a650cdbc985d7123aedb7dbb5994446ad427cc4d\", \"compositor_unittests\": \"11aee18970a18d38232edaecceb22fbbbf153182\", \"content_browsertests\": \"8c99a32def27c5cc67abd5914d9fb1006b1e908d\", \"content_shell_crash_test\": \"fadcfcc8b8a176711819583bc27a36c69afff785\", \"content_unittests\": \"1bfb5efec7974d05bf7948a31e0fbf7942b25635\", \"crashpad_tests\": \"97dca390746ab8567b9524558b89a33e0117cc59\", \"cronet_tests\": \"43ad178ed4705e7f6c89b175ca15d6cbdb5a5187\", \"cronet_unittests\": \"d2cbd25444ae962340b98683ba6d897d3fb006d2\", \"crypto_unittests\": \"11dde5e8a63f1a3f08858116887b4741e24d9071\", \"dbus_unittests\": \"9ca9077bb30479a16583f3ad19e7871f79bd83b8\", \"device_unittests\": \"bfff2f1315498a82f5ebb10c429d06ad301a23b2\", \"display_unittests\": \"5cc31e8bf89686d9ffcfdc7a05275c8247bba18b\", \"events_unittests\": \"d2ce361f42ec01e32aa15bba7f836d01975a2aac\", \"extensions_browsertests\": \"078f82261065a9635b2604a675c9e635452759fc\", \"extensions_unittests\": \"faad0538ba0851e895b3fecda922d65c0a1afbfd\", \"filesystem_service_unittests\": \"0b01fcc33be9dc424593c044c839c7f7662da738\", \"flatbuffers_unittests\": \"ca71348ce5934d53fb892115f8e6e8a2436d5e6c\", \"gcm_unit_tests\": \"597894371705a24c465e37476abfcfc6b410faee\", \"gfx_unittests\": \"7305f15fc5abb13c36bdfd51007440a5aaa6199b\", \"gin_unittests\": \"7eebd351168cd96c81b921053efa297f52b4591e\", \"google_apis_unittests\": \"a90ef247b77774209c086518900baf06e00d7bdc\", \"gpu_unittests\": \"673a53218ec42733ad04cfdeff3cd438ff91ebe2\", \"grit_python_unittests\": \"a906de1211aa1e6d0b6deb8a9a162d6998fb6336\", \"gwp_asan_unittests\": \"8fc6d56be132770b2ee265dbeddb3b544a9642a0\", \"headless_browsertests\": \"8bb8eedc8325558580839407745f8d3d1ecadd35\", \"headless_unittests\": \"38bc8247eb0b4a8f3dfe8ebdaee84db791066904\", \"interactive_ui_tests\": \"f0ffcbca383badae64df969f54f4d3fcbce2c1e7\", \"ipc_tests\": \"5dcd2a9028d4c558e52cdd0490fde05ad9015584\", \"jingle_unittests\": \"6d96e63396670f2f774a8def592747e33233778d\", \"latency_unittests\": \"3a45aacc9575df4fdcae70f02f629d36754f95b0\", \"libjingle_xmpp_unittests\": \"85428440cb0d633b009a13a575600b3b3f612aa8\", \"media_blink_unittests\": \"17e58b72978ae64e856f5b87af190a08e0f50bb4\", \"media_service_unittests\": \"70c9d38c557614f04299528b82b97a80432a1067\", \"media_unittests\": \"ef602bdfc08fc7f0b6706e61c65f1ff449472aa4\", \"message_center_unittests\": \"dc751c965a61dafb4e586a372946d053f35fa867\", \"metrics_python_tests\": \"c1294b4d7509ac5c276e39eb5c9c37ba455bb280\", \"midi_unittests\": \"0300237b144968e57853e02fe1cda46eec29617f\", \"mojo_core_unittests\": \"df61682da07ad1c046f0577d98fec4c0f422bc18\", \"mojo_unittests\": \"e5e6c9b7a20a996d17e24054cb17b2bef4aa364c\", \"nacl_helper_nonsfi_unittests\": \"53cd259b7b1e63cfce3b0db5e6fbd19310b9abc2\", \"nacl_loader_unittests\": \"886b674e425e8dd28eb849e60ad97513769825ae\", \"native_theme_unittests\": \"cad641b8a009fec24806071510b2eca015c1ecbf\", \"net_unittests\": \"35939673035d6732ed9cc40df56f2afc2dbcef76\", \"pdf_unittests\": \"0761676e4735ae73ef87c25fa3b2bc5a5ac94a2e\", \"perfetto_unittests\": \"977903dd56561d1d863119e8fe34709a9264e30b\", \"ppapi_unittests\": \"7ff7d8f5b4491bb3315804ef134f54c01543092c\", \"printing_unittests\": \"2a9133e94b02263751488cdaacee92aaf0990a83\", \"remoting_unittests\": \"c70f10705d0e5cffc380a424ab62f7de4c05cb50\", \"sandbox_linux_unittests\": \"8fd52c5d27e8e0be6081b7efa31224dae8cb44f6\", \"service_manager_unittests\": \"6b83fc0bf853ee16b070d0dbd6338d93d57d56e7\", \"services_unittests\": \"456335d9bca34591b13403ada7040cef847defe3\", \"shell_dialogs_unittests\": \"1436fdc1506a1b0e97ae8315a4dacfa62cc6f63d\", \"skia_unittests\": \"79c996e1460f6750292336e1a79fd5653e941c46\", \"snapshot_unittests\": \"27ab82b49b374479e2416f673298500ecdc97bb6\", \"sql_unittests\": \"e0b85af04651f692556bb86f3d7c34732b893b77\", \"storage_unittests\": \"73a6a78c059e711385834b278ba2c16e128c405a\", \"sync_integration_tests\": \"1738b7bf602aa6a3e68ed4709151a1c1155153eb\", \"telemetry_gpu_unittests\": \"0fb1d6afabf34f80872a585059e8ab815cd65979\", \"telemetry_perf_unittests\": \"73d4f7349be01eb60d3661938ae98c69430bd9dd\", \"telemetry_unittests\": \"a0cad6bebe29e448ca92164efe4fe97506b59ed0\", \"traffic_annotation_auditor_unittests\": \"33fa11c76213c0b6c629d9a21a4555bae13eeca9\", \"ui_base_unittests\": \"94e8fc60b18f50cadd32edd0f4ec784369f18636\", \"ui_touch_selection_unittests\": \"966b7b45ff8f308f44c3dc7f4cc69695e1d9017b\", \"unit_tests\": \"59fbaabed632f0bac2ac5b3e84bbbbdbd5b59453\", \"url_unittests\": \"34652ba7cca8da879ad7ab10b09f204ed0a12188\", \"views_perftests\": \"50128ec2865e9c53454d71a2d10e5a485af88efe\", \"views_unittests\": \"d952840d60054ed8d330b58a3e2f0ce8c4b5183d\", \"viz_unittests\": \"203a0c47f007236e5f7ee07b553b974506330f99\", \"vr_common_unittests\": \"7c26f7d1c57090c9a8b2ddbaf0fb2859d6acd744\", \"vr_pixeltests\": \"cd3ea89dad2a1d76af6af3ae843d3f1c9675fb4e\", \"webdriver_wpt_tests\": \"16bc2277d5ba296aea685495c496a840825c35b3\", \"weblayer_browsertests\": \"7d24dc0a105d2f7be884558c2ac9cc4edef5bfe4\", \"wm_unittests\": \"995fdf9d8811709bb09a1ff80fc6d2faffe9069d\", \"wtf_unittests\": \"d17ce118e225ff0acc5f9028a6c3528ef9cb6f47\", \"xr_browser_tests\": \"cb5341284e098de40b4424eda4b72b6e95847043\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2b15148fa2a471165482335bd523b5eee69ace70a2c04528f88245080a969046_v2\", \"builder_51603c189459d949c9f9b05c6193f37cd30704e77931c7dc651854102fbe3f3d_v2\", \"builder_97b15bccca8d35086c1bc50eecf0b53d98289cdc65be75a287efcc384d1f34ed_v2\", \"builder_fa0031875b7f2f11c46443055ab400bfaeb07a1d2a142b58c631314016c04e0a_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-13-jmt6\"], \"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\": [\"4809-bb2013c\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1580946793615298",
"status": "COMPLETED",
"status_changed_ts": "1580950100073938",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168275",
"builder:Linux Builder (dbg)",
"buildset:commit/git/5068d53694c51192af3b7b7518477ceeff52df02",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5068d53694c51192af3b7b7518477ceeff52df02",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054681201397391792",
"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/8889251138686860032/+/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:4a30930045140110",
"user_agent:luci-scheduler"
],
"updated_ts": "1580950100094037",
"url": "https://ci.chromium.org/b/8889251138686860032",
"utcnow_ts": "1580950100861252"
}
}
[00:48:20.875] Invocation finished in 59m43.686919467s with status SUCCEEDED