[10:33:52.356] New invocation is queued and will start shortly
[10:33:53.726] Starting the invocation (attempt 1)
[10:33:53.763] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:33:53.763] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:33:53.763] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9054278217754978512",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"563ebbe80c8993978cee672c5da9c6ab4fca451b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9054278217754978512",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/563ebbe80c8993978cee672c5da9c6ab4fca451b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/563ebbe80c8993978cee672c5da9c6ab4fca451b",
"gitiles_ref:refs/heads/master"
]
}
[10:33:54.607] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1581330833933038",
"id": "8888848155435704912",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"563ebbe80c8993978cee672c5da9c6ab4fca451b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1581330834436561",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168445",
"builder:Linux Builder (dbg)",
"buildset:commit/git/563ebbe80c8993978cee672c5da9c6ab4fca451b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/563ebbe80c8993978cee672c5da9c6ab4fca451b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054278217754978512",
"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/8888848155435704912/+/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": "1581330834436662",
"url": "https://ci.chromium.org/b/8888848155435704912",
"utcnow_ts": "1581330834595522"
}
}
[10:33:54.607] Task URL: https://ci.chromium.org/b/8888848155435704912
[10:33:54.608] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:2:0) after 1m0s
[10:34:54.661] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:2:0)
[10:34:54.661] Timer tick, asking Buildbucket for the build status
[10:34:54.850] Build 8888848155435704912: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:34:54.850] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:3:0) after 1m0s
[10:35:54.892] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:3:0)
[10:35:54.892] Timer tick, asking Buildbucket for the build status
[10:35:55.160] Build 8888848155435704912: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:35:55.160] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:4:0) after 1m0s
[10:36:55.199] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:4:0)
[10:36:55.199] Timer tick, asking Buildbucket for the build status
[10:36:55.396] Build 8888848155435704912: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:36:55.396] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:5:0) after 1m0s
[10:37:55.443] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:5:0)
[10:37:55.443] Timer tick, asking Buildbucket for the build status
[10:37:56.820] Build 8888848155435704912: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:37:56.820] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:6:0) after 1m0s
[10:38:05.918] Received PubSub notification, asking Buildbucket for the build status
[10:38:06.196] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:56.946] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:6:0)
[10:38:56.946] Timer tick, asking Buildbucket for the build status
[10:38:57.166] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:57.166] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:8:0) after 1m0s
[10:39:57.210] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:8:0)
[10:39:57.210] Timer tick, asking Buildbucket for the build status
[10:39:57.362] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:57.362] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:9:0) after 1m0s
[10:40:57.382] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:9:0)
[10:40:57.382] Timer tick, asking Buildbucket for the build status
[10:40:57.949] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:57.949] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:10:0) after 1m0s
[10:41:57.968] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:10:0)
[10:41:57.968] Timer tick, asking Buildbucket for the build status
[10:41:59.500] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:59.500] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:11:0) after 1m0s
[10:42:59.637] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:11:0)
[10:42:59.637] Timer tick, asking Buildbucket for the build status
[10:43:00.141] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:00.141] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:12:0) after 1m0s
[10:44:00.168] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:12:0)
[10:44:00.168] Timer tick, asking Buildbucket for the build status
[10:44:00.365] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:00.365] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:13:0) after 1m0s
[10:45:00.435] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:13:0)
[10:45:00.435] Timer tick, asking Buildbucket for the build status
[10:45:00.608] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:00.608] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:14:0) after 1m0s
[10:46:00.702] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:14:0)
[10:46:00.702] Timer tick, asking Buildbucket for the build status
[10:46:00.910] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:00.910] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:15:0) after 1m0s
[10:47:00.931] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:15:0)
[10:47:00.931] Timer tick, asking Buildbucket for the build status
[10:47:02.012] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:02.012] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:16:0) after 1m0s
[10:48:02.031] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:16:0)
[10:48:02.031] Timer tick, asking Buildbucket for the build status
[10:48:02.247] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:02.247] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:17:0) after 1m0s
[10:49:02.304] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:17:0)
[10:49:02.304] Timer tick, asking Buildbucket for the build status
[10:49:02.456] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:02.456] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:18:0) after 1m0s
[10:50:02.517] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:18:0)
[10:50:02.517] Timer tick, asking Buildbucket for the build status
[10:50:02.692] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:02.692] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:19:0) after 1m0s
[10:51:02.761] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:19:0)
[10:51:02.761] Timer tick, asking Buildbucket for the build status
[10:51:02.849] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:02.849] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:20:0) after 1m0s
[10:52:02.959] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:20:0)
[10:52:02.959] Timer tick, asking Buildbucket for the build status
[10:52:05.799] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:05.799] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:21:0) after 1m0s
[10:53:05.849] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:21:0)
[10:53:05.849] Timer tick, asking Buildbucket for the build status
[10:53:06.694] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:06.694] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:22:0) after 1m0s
[10:54:06.823] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:22:0)
[10:54:06.823] Timer tick, asking Buildbucket for the build status
[10:54:07.573] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:07.573] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:23:0) after 1m0s
[10:55:07.723] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:23:0)
[10:55:07.723] Timer tick, asking Buildbucket for the build status
[10:55:08.354] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:08.354] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:24:0) after 1m0s
[10:56:08.410] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:24:0)
[10:56:08.410] Timer tick, asking Buildbucket for the build status
[10:56:08.606] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:08.606] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:25:0) after 1m0s
[10:57:08.659] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:25:0)
[10:57:08.659] Timer tick, asking Buildbucket for the build status
[10:57:08.908] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:08.908] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:26:0) after 1m0s
[10:58:08.986] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:26:0)
[10:58:08.986] Timer tick, asking Buildbucket for the build status
[10:58:09.633] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:09.633] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:27:0) after 1m0s
[10:59:09.678] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:27:0)
[10:59:09.678] Timer tick, asking Buildbucket for the build status
[10:59:10.092] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:10.092] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:28:0) after 1m0s
[11:00:10.178] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:28:0)
[11:00:10.178] Timer tick, asking Buildbucket for the build status
[11:00:11.521] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:11.521] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:29:0) after 1m0s
[11:01:11.623] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:29:0)
[11:01:11.623] Timer tick, asking Buildbucket for the build status
[11:01:11.847] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:11.847] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:30:0) after 1m0s
[11:02:11.836] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:30:0)
[11:02:11.836] Timer tick, asking Buildbucket for the build status
[11:02:12.284] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:12.284] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:31:0) after 1m0s
[11:03:12.269] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:31:0)
[11:03:12.269] Timer tick, asking Buildbucket for the build status
[11:03:12.790] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:12.790] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:32:0) after 1m0s
[11:04:12.874] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:32:0)
[11:04:12.874] Timer tick, asking Buildbucket for the build status
[11:04:13.605] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:13.605] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:33:0) after 1m0s
[11:05:13.598] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:33:0)
[11:05:13.598] Timer tick, asking Buildbucket for the build status
[11:05:13.755] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:13.755] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:34:0) after 1m0s
[11:06:13.765] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:34:0)
[11:06:13.765] Timer tick, asking Buildbucket for the build status
[11:06:13.905] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:13.905] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:35:0) after 1m0s
[11:07:14.009] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:35:0)
[11:07:14.009] Timer tick, asking Buildbucket for the build status
[11:07:15.317] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:15.317] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:36:0) after 1m0s
[11:08:15.360] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:36:0)
[11:08:15.360] Timer tick, asking Buildbucket for the build status
[11:08:16.310] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:16.310] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:37:0) after 1m0s
[11:09:16.455] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:37:0)
[11:09:16.455] Timer tick, asking Buildbucket for the build status
[11:09:16.647] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:16.647] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:38:0) after 1m0s
[11:10:16.711] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:38:0)
[11:10:16.711] Timer tick, asking Buildbucket for the build status
[11:10:17.610] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:17.610] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:39:0) after 1m0s
[11:11:17.628] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:39:0)
[11:11:17.628] Timer tick, asking Buildbucket for the build status
[11:11:18.559] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:18.559] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:40:0) after 1m0s
[11:12:18.646] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:40:0)
[11:12:18.646] Timer tick, asking Buildbucket for the build status
[11:12:18.807] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:18.807] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:41:0) after 1m0s
[11:13:18.921] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:41:0)
[11:13:18.921] Timer tick, asking Buildbucket for the build status
[11:13:19.097] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:19.097] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:42:0) after 1m0s
[11:14:19.253] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:42:0)
[11:14:19.253] Timer tick, asking Buildbucket for the build status
[11:14:19.506] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:19.506] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:43:0) after 1m0s
[11:15:19.613] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:43:0)
[11:15:19.613] Timer tick, asking Buildbucket for the build status
[11:15:19.827] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:19.827] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:44:0) after 1m0s
[11:16:19.867] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:44:0)
[11:16:19.867] Timer tick, asking Buildbucket for the build status
[11:16:19.998] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:19.998] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:45:0) after 1m0s
[11:17:20.064] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:45:0)
[11:17:20.064] Timer tick, asking Buildbucket for the build status
[11:17:22.192] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:22.192] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:46:0) after 1m0s
[11:18:22.288] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:46:0)
[11:18:22.288] Timer tick, asking Buildbucket for the build status
[11:18:22.478] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:22.478] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:47:0) after 1m0s
[11:19:22.682] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:47:0)
[11:19:22.682] Timer tick, asking Buildbucket for the build status
[11:19:24.284] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:24.284] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:48:0) after 1m0s
[11:20:24.320] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:48:0)
[11:20:24.320] Timer tick, asking Buildbucket for the build status
[11:20:24.475] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:24.475] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:49:0) after 1m0s
[11:21:24.536] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:49:0)
[11:21:24.536] Timer tick, asking Buildbucket for the build status
[11:21:24.717] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:24.717] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:50:0) after 1m0s
[11:22:24.811] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:50:0)
[11:22:24.811] Timer tick, asking Buildbucket for the build status
[11:22:25.279] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:25.279] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:51:0) after 1m0s
[11:23:25.398] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:51:0)
[11:23:25.398] Timer tick, asking Buildbucket for the build status
[11:23:25.915] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:25.915] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:52:0) after 1m0s
[11:24:25.931] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:52:0)
[11:24:25.931] Timer tick, asking Buildbucket for the build status
[11:24:26.546] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:26.546] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:53:0) after 1m0s
[11:25:26.611] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:53:0)
[11:25:26.611] Timer tick, asking Buildbucket for the build status
[11:25:26.816] Build 8888848155435704912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:26.816] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054278217754978512:54:0) after 1m0s
[11:25:29.828] Received PubSub notification, asking Buildbucket for the build status
[11:25:30.325] Build 8888848155435704912: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:25:30.325] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1581333929114059",
"created_by": "project:chromium",
"created_ts": "1581330833933038",
"id": "8888848155435704912",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"563ebbe80c8993978cee672c5da9c6ab4fca451b\"}}",
"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-70-wzvt\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168445, \"got_angle_revision\": \"572d63a71683ca287701af2de0c9ccb22e631791\", \"got_dawn_revision\": \"fb01a6024e4d8ba45047baa410783f7774e6cd9e\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"563ebbe80c8993978cee672c5da9c6ab4fca451b\", \"got_revision_cp\": \"refs/heads/master@{#739831}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"b3b2a4fc756f8f6dca980c75d785e06134b9d5f8\", \"got_v8_revision\": \"25c49d2bd6cbdc72b0779545d2a32406657befda\", \"got_v8_revision_cp\": \"refs/heads/8.2.49@{#1}\", \"got_webrtc_revision\": \"d4c3c3a454215b21f02d2a772a729b4c87d6b33e\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30486}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"563ebbe80c8993978cee672c5da9c6ab4fca451b\", \"swarm_hashes_refs/heads/master(at){#739831}_without_patch\": {\"accessibility_unittests\": \"3a23f268a01d7f63d07b2b9dd6b6e16919a912e3\", \"angle_unittests\": \"471673f2ff8d0f6194806a19f674f13bc3ecafbc\", \"app_shell_unittests\": \"e341c13de8aab2732a9e45ef60830da87a65b9b7\", \"aura_unittests\": \"227e06f0fb7b5defa42f4c8312579053104ee1a4\", \"base_unittests\": \"2571097c34b85293d7fab6ff3d2d9a5d2ce0c4a9\", \"base_util_unittests\": \"6bc85bb60cbb5dca10ab8c06303aa441fa9e1eaa\", \"blink_common_unittests\": \"b603a3d73d16186fe660272fc24b3d25e5d47ed0\", \"blink_fuzzer_unittests\": \"6441e7b1f478c5cfc51527811ebe28060f4d8c0a\", \"blink_heap_unittests\": \"57441f13947a92f449922899811f649fb7690103\", \"blink_platform_unittests\": \"64954cf1ea15f430adf6d643d7e908deac2ee194\", \"blink_python_tests\": \"56ab96839ce69ed317552f52a354e85263e5dd4b\", \"blink_unittests\": \"1fe5105a2dc1c60513e82973601ce3284a17cd9d\", \"blink_web_tests\": \"20ed2a3b106efc8ab6454c649c77e92a69db8819\", \"boringssl_crypto_tests\": \"6553b69b6c8750473633043bec485df4b7b5cf54\", \"boringssl_ssl_tests\": \"b689ff06ab02ce805925fd7ec587597a4ad614b6\", \"browser_tests\": \"f57f43c9ebba81ffe648db17a41615358eeb550b\", \"cacheinvalidation_unittests\": \"aeaf85c9a0850ccd6b0920e338bff137e7bbe8db\", \"capture_unittests\": \"3b30f46b13bb14b66f16c432f129f5684ebe30d1\", \"cast_unittests\": \"ae1c304cf812c37bf642bc9cb94fb8fadc56eba7\", \"cc_unittests\": \"ca5758038fa3fefbebd84004e4cb7c0fcf2c1d5d\", \"chrome_app_unittests\": \"3a9b7cbdd51e8cccfc165cd8a76a16030d46ee07\", \"chromedriver_unittests\": \"afd3648c04d037f6c87de6ddee16aa517bc39a42\", \"components_browsertests\": \"116268c284f318eb51602c11d98039741e09a220\", \"components_unittests\": \"08ca02124d516144b34450d14835351486f87a00\", \"compositor_unittests\": \"ad59f97089efd9b178b59b1f7c11f3d03f095a92\", \"content_browsertests\": \"105690a56d299a6982338fb7f90ea3b6c1757d2d\", \"content_shell_crash_test\": \"66cbc15afe202d3cf219c58fdf268c707ba87250\", \"content_unittests\": \"4643516b3e4e5e423bcc76f7d2befe5a2ff39167\", \"crashpad_tests\": \"3d6d5fe58ee4e84e20cdb23ed0b350e280348620\", \"cronet_tests\": \"414838aff2ac7db9796133da76837a50c51247c7\", \"cronet_unittests\": \"853fbd5a69208ac9fd16f62af1817cc22af5ee0c\", \"crypto_unittests\": \"91b86a262952bc7c717b33b7637bb01836f2e950\", \"dbus_unittests\": \"7160ec40f87b36ccfd93337f520fbd3abaf7b566\", \"device_unittests\": \"aea4f2a16e88087d8f96a160ef622160df683d97\", \"display_unittests\": \"4e68fe3161c3115a324b121bc5641c2c563a6156\", \"events_unittests\": \"9508da6e55e5f1e1bb8f794a84cf576355988da3\", \"extensions_browsertests\": \"95d7bc12b2ae79c5c973ab52cc96b03b863f1ae9\", \"extensions_unittests\": \"90aee7a0a4ad166dc83d079dc5845e14be83bc4b\", \"filesystem_service_unittests\": \"7ef79396c7aaf57c99502a3375821ba141e9b770\", \"flatbuffers_unittests\": \"10f81979c843dd4ade492dcd4ea4433f130db6fb\", \"gcm_unit_tests\": \"b0d15bef106af338e4d5e0bcd6c1e1a5d89d7040\", \"gfx_unittests\": \"6eabec10a9750854eefe5b5dd29ea602c445b108\", \"gin_unittests\": \"001509a8a7ea19c020b3b55fee8583e01b34ec9d\", \"google_apis_unittests\": \"fba8951990727cbc439b805f7f7e522c9cfdfd26\", \"gpu_unittests\": \"8fa1c884c1a9f7318d61d06fb8dd60aae9d585ec\", \"grit_python_unittests\": \"18cc72bb18610618d19d87d30087c762f088d3cd\", \"gwp_asan_unittests\": \"59cb66df4df60437db64962a573e8a0bd8e4ba39\", \"headless_browsertests\": \"6464dd3082783c2f040885066c2fb2cead0a73d7\", \"headless_unittests\": \"2fd8d95933fe3650386e12d1ba51ac647f865e0a\", \"interactive_ui_tests\": \"cff8cc4e4d5143145c5d6f32d729a42024ffae8e\", \"ipc_tests\": \"e314de1ce8c4827c154a24b793ed5debb22903a6\", \"jingle_unittests\": \"4bdb753b471a1702777a5af2c225a91716b93ff7\", \"latency_unittests\": \"68edaf674fbb48ca4e9d4212cc0055e8afc6c6e0\", \"libjingle_xmpp_unittests\": \"5e2789d03946ef1bd9be4eb2126850a1ab9901a1\", \"media_blink_unittests\": \"1ba82fbf8bd02658c9f8642bfce94ef3a0f747d5\", \"media_service_unittests\": \"3da423f11e25bcafaf30d7f79f8dc310593a6364\", \"media_unittests\": \"ff539d6b9863770c60aaf6acaa5a37eb3b390153\", \"message_center_unittests\": \"bec4c9d2ed81740699925b669fd1d0ae02343670\", \"metrics_python_tests\": \"0c427cb6f5e9c5842fe440f40c7d34607e09e405\", \"midi_unittests\": \"8e85606d42f9629f40ea7dd15c282d6949bec0c2\", \"mojo_core_unittests\": \"d7bb296793f7d6081d5444104dcd5e63ca3c73f6\", \"mojo_unittests\": \"bb8a09c65bdfdbff3b8e61dc7fbe195384d0b9e3\", \"nacl_helper_nonsfi_unittests\": \"3d6a4ed66c02f45673615c72aebfc97c2f218183\", \"nacl_loader_unittests\": \"016c279da8de2bf02dd492a9e20c27f21faa14a1\", \"native_theme_unittests\": \"a7341bf43ddb6b87fddc86c3d9d11acbad9b473f\", \"net_unittests\": \"ecdb20b846b8a808c2d3560c346ddfece290bae9\", \"pdf_unittests\": \"9dd826cde91f1d083426f5c3a4e07206e046c298\", \"perfetto_unittests\": \"edd0ecea336383caeb7b24da6796668d310fb8eb\", \"ppapi_unittests\": \"dbb34f0094cd3dc9dcaae5bfc49de0354ab350a7\", \"printing_unittests\": \"419bbe0dd84542f42f02dc521b85d542d065b520\", \"remoting_unittests\": \"7559c29ee2a9fcf5c6af5ed4a00042fe2aa7ac4d\", \"sandbox_linux_unittests\": \"86c408f67545e2144d19f5736b79d71f32215576\", \"service_manager_unittests\": \"41758ca130f54cce3c61865a2f1bc3944451e35f\", \"services_unittests\": \"63d2adf465261581d72599a73bc3f3915ecf0eec\", \"shell_dialogs_unittests\": \"96b4aa1577300884d82d37fe301153af17176199\", \"skia_unittests\": \"7d231d76c6815ec09156d607c812fb47528dbaf4\", \"snapshot_unittests\": \"566ed9cb8cfdce891af3195571a02cf4b259efc7\", \"sql_unittests\": \"cae905edcdb451d48eaada752806874ceb8e405b\", \"storage_unittests\": \"8fa6a626cdd98984d8f9ee7de22fbbebe67b0625\", \"sync_integration_tests\": \"2f548eaf9460b3995ea99a15cb54faf4f55df517\", \"telemetry_gpu_unittests\": \"04f48e66ecb14365639ea6d66b67cebd1931fe0c\", \"telemetry_perf_unittests\": \"6feb4f80178d7a288f52de9d6a9f892f12cd629c\", \"telemetry_unittests\": \"e8091a00ca4708dd5e0896b67d9a32fdd174bad0\", \"traffic_annotation_auditor_unittests\": \"735519c7d2e281b82d1459b1cbf43b2c2991d66a\", \"ui_base_unittests\": \"cfb328553db5e261cfa2bc3305c24cc52aa89724\", \"ui_touch_selection_unittests\": \"397738c5e85b30643c1f5eefc4786cf42738baaa\", \"unit_tests\": \"1966c845f77d5b5cfd97ba298cc20202b480eacf\", \"url_unittests\": \"8ec72d50b3c0d3c50dc8a6ae0c97be4fd8fde567\", \"views_perftests\": \"0b050e109b0f6a51728721d3235dc958192d597f\", \"views_unittests\": \"8154e7c761ff341cc51553a89aac85a11a2c4a82\", \"viz_unittests\": \"ba23f6e5ebaf55c51c21148c5a7675f7933cf4a5\", \"vr_common_unittests\": \"0a895756848df73cb91ea3d7274b26a4bb4d44b2\", \"vr_pixeltests\": \"c666e515ef67e8bf46856ef6f560b9b45751c1ff\", \"webdriver_wpt_tests\": \"a13fef4fb72c3d1f38d4ba742276fddfd5474fef\", \"weblayer_browsertests\": \"f31a48b735300e77b1fad2f65db6e6018e53301f\", \"wm_unittests\": \"75c3dace2cdfd5be63dc8c242a1731573447ae7c\", \"wtf_unittests\": \"5d440d5ce06abeb0dedfd6893049fa54dc676488\", \"xr_browser_tests\": \"4438b2435be01b928e143e23e373071d29e46fb0\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_2295bac873f7edd8c9763e89419d85b38a875955550d968c436d775d3f06377b_v2\", \"builder_3d74f5cea4cbf91e4b26f92a522c5c74e61b738440ef29ba16f5c9a596a5b201_v2\", \"builder_c33cb68a27562f3c0030aeadb8872bc9395ca543fdfb70fdbe284a5ae715c202_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-70-wzvt\"], \"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\": [\"4812-ea7e8fc\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1581331084967171",
"status": "COMPLETED",
"status_changed_ts": "1581333929440493",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168445",
"builder:Linux Builder (dbg)",
"buildset:commit/git/563ebbe80c8993978cee672c5da9c6ab4fca451b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/563ebbe80c8993978cee672c5da9c6ab4fca451b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054278217754978512",
"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/8888848155435704912/+/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:4a477b2d42e9b510",
"user_agent:luci-scheduler"
],
"updated_ts": "1581333929444695",
"url": "https://ci.chromium.org/b/8888848155435704912",
"utcnow_ts": "1581333930314089"
}
}
[11:25:30.325] Invocation finished in 51m37.987238417s with status SUCCEEDED