[22:47:43.235] New invocation is queued and will start shortly
[22:47:47.552] Starting the invocation (attempt 1)
[22:47:47.664] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:47:47.664] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:47:47.664] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9054594435897229344",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"96d1455e14c27e84bc6d85fec7bb116553093bcb\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9054594435897229344",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/96d1455e14c27e84bc6d85fec7bb116553093bcb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/96d1455e14c27e84bc6d85fec7bb116553093bcb",
"gitiles_ref:refs/heads/master"
]
}
[22:47:48.540] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1581029267823895",
"id": "8889164370421271104",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"96d1455e14c27e84bc6d85fec7bb116553093bcb\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1581029268351354",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168316",
"builder:Linux Builder (dbg)",
"buildset:commit/git/96d1455e14c27e84bc6d85fec7bb116553093bcb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/96d1455e14c27e84bc6d85fec7bb116553093bcb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054594435897229344",
"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/8889164370421271104/+/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": "1581029268351537",
"url": "https://ci.chromium.org/b/8889164370421271104",
"utcnow_ts": "1581029268525864"
}
}
[22:47:48.540] Task URL: https://ci.chromium.org/b/8889164370421271104
[22:47:48.540] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:2:0) after 1m0s
[22:48:48.721] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:2:0)
[22:48:48.721] Timer tick, asking Buildbucket for the build status
[22:48:48.987] Build 8889164370421271104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:48:48.987] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:3:0) after 1m0s
[22:49:49.046] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:3:0)
[22:49:49.046] Timer tick, asking Buildbucket for the build status
[22:49:49.243] Build 8889164370421271104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:49:49.243] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:4:0) after 1m0s
[22:50:49.345] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:4:0)
[22:50:49.345] Timer tick, asking Buildbucket for the build status
[22:50:49.538] Build 8889164370421271104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:50:49.538] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:5:0) after 1m0s
[22:51:49.586] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:5:0)
[22:51:49.586] Timer tick, asking Buildbucket for the build status
[22:51:50.354] Build 8889164370421271104: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:51:50.354] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:6:0) after 1m0s
[22:52:31.330] Received PubSub notification, asking Buildbucket for the build status
[22:52:32.056] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:50.472] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:6:0)
[22:52:50.472] Timer tick, asking Buildbucket for the build status
[22:52:50.604] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:50.604] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:8:0) after 1m0s
[22:53:50.846] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:8:0)
[22:53:50.846] Timer tick, asking Buildbucket for the build status
[22:53:51.218] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:51.218] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:9:0) after 1m0s
[22:54:51.496] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:9:0)
[22:54:51.496] Timer tick, asking Buildbucket for the build status
[22:54:51.841] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:51.841] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:10:0) after 1m0s
[22:55:52.192] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:10:0)
[22:55:52.192] Timer tick, asking Buildbucket for the build status
[22:55:52.356] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:52.356] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:11:0) after 1m0s
[22:56:52.516] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:11:0)
[22:56:52.516] Timer tick, asking Buildbucket for the build status
[22:56:52.692] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:52.692] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:12:0) after 1m0s
[22:57:52.731] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:12:0)
[22:57:52.731] Timer tick, asking Buildbucket for the build status
[22:57:53.095] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:53.095] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:13:0) after 1m0s
[22:58:53.191] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:13:0)
[22:58:53.191] Timer tick, asking Buildbucket for the build status
[22:58:53.379] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:53.379] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:14:0) after 1m0s
[22:59:53.438] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:14:0)
[22:59:53.438] Timer tick, asking Buildbucket for the build status
[22:59:53.805] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:53.805] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:15:0) after 1m0s
[23:00:53.977] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:15:0)
[23:00:53.977] Timer tick, asking Buildbucket for the build status
[23:00:54.109] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:54.109] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:16:0) after 1m0s
[23:01:54.253] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:16:0)
[23:01:54.253] Timer tick, asking Buildbucket for the build status
[23:01:56.007] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:56.007] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:17:0) after 1m0s
[23:02:56.275] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:17:0)
[23:02:56.275] Timer tick, asking Buildbucket for the build status
[23:02:56.450] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:56.450] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:18:0) after 1m0s
[23:03:56.598] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:18:0)
[23:03:56.598] Timer tick, asking Buildbucket for the build status
[23:03:57.069] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:57.069] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:19:0) after 1m0s
[23:04:57.168] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:19:0)
[23:04:57.168] Timer tick, asking Buildbucket for the build status
[23:04:57.298] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:57.298] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:20:0) after 1m0s
[23:05:57.309] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:20:0)
[23:05:57.309] Timer tick, asking Buildbucket for the build status
[23:05:57.837] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:57.837] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:21:0) after 1m0s
[23:06:57.869] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:21:0)
[23:06:57.869] Timer tick, asking Buildbucket for the build status
[23:06:59.173] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:59.173] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:22:0) after 1m0s
[23:07:59.185] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:22:0)
[23:07:59.185] Timer tick, asking Buildbucket for the build status
[23:07:59.334] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:59.334] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:23:0) after 1m0s
[23:08:59.369] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:23:0)
[23:08:59.369] Timer tick, asking Buildbucket for the build status
[23:09:00.292] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:00.292] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:24:0) after 1m0s
[23:10:00.415] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:24:0)
[23:10:00.415] Timer tick, asking Buildbucket for the build status
[23:10:00.563] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:00.563] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:25:0) after 1m0s
[23:11:00.555] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:25:0)
[23:11:00.555] Timer tick, asking Buildbucket for the build status
[23:11:01.126] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:01.126] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:26:0) after 1m0s
[23:12:01.132] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:26:0)
[23:12:01.132] Timer tick, asking Buildbucket for the build status
[23:12:01.290] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:01.290] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:27:0) after 1m0s
[23:13:01.395] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:27:0)
[23:13:01.395] Timer tick, asking Buildbucket for the build status
[23:13:02.259] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:02.259] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:28:0) after 1m0s
[23:14:02.314] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:28:0)
[23:14:02.314] Timer tick, asking Buildbucket for the build status
[23:14:02.535] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:02.536] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:29:0) after 1m0s
[23:15:02.560] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:29:0)
[23:15:02.560] Timer tick, asking Buildbucket for the build status
[23:15:02.715] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:02.715] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:30:0) after 1m0s
[23:16:02.871] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:30:0)
[23:16:02.871] Timer tick, asking Buildbucket for the build status
[23:16:03.097] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:03.097] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:31:0) after 1m0s
[23:17:03.144] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:31:0)
[23:17:03.144] Timer tick, asking Buildbucket for the build status
[23:17:03.603] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:03.603] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:32:0) after 1m0s
[23:18:03.651] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:32:0)
[23:18:03.651] Timer tick, asking Buildbucket for the build status
[23:18:04.230] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:04.230] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:33:0) after 1m0s
[23:19:04.328] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:33:0)
[23:19:04.328] Timer tick, asking Buildbucket for the build status
[23:19:04.523] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:04.523] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:34:0) after 1m0s
[23:20:04.862] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:34:0)
[23:20:04.862] Timer tick, asking Buildbucket for the build status
[23:20:05.647] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:05.648] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:35:0) after 1m0s
[23:21:05.755] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:35:0)
[23:21:05.755] Timer tick, asking Buildbucket for the build status
[23:21:05.929] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:05.929] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:36:0) after 1m0s
[23:22:06.098] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:36:0)
[23:22:06.098] Timer tick, asking Buildbucket for the build status
[23:22:06.695] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:06.695] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:37:0) after 1m0s
[23:23:06.781] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:37:0)
[23:23:06.781] Timer tick, asking Buildbucket for the build status
[23:23:09.116] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:09.116] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:38:0) after 1m0s
[23:24:09.202] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:38:0)
[23:24:09.202] Timer tick, asking Buildbucket for the build status
[23:24:09.379] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:09.379] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:39:0) after 1m0s
[23:25:09.516] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:39:0)
[23:25:09.516] Timer tick, asking Buildbucket for the build status
[23:25:09.938] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:09.938] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:40:0) after 1m0s
[23:26:09.995] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:40:0)
[23:26:09.995] Timer tick, asking Buildbucket for the build status
[23:26:10.149] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:10.149] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:41:0) after 1m0s
[23:27:10.192] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:41:0)
[23:27:10.192] Timer tick, asking Buildbucket for the build status
[23:27:10.485] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:10.485] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:42:0) after 1m0s
[23:28:10.571] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:42:0)
[23:28:10.571] Timer tick, asking Buildbucket for the build status
[23:28:11.159] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:11.159] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:43:0) after 1m0s
[23:29:11.268] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:43:0)
[23:29:11.268] Timer tick, asking Buildbucket for the build status
[23:29:11.441] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:11.441] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:44:0) after 1m0s
[23:30:11.551] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:44:0)
[23:30:11.551] Timer tick, asking Buildbucket for the build status
[23:30:11.702] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:11.702] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:45:0) after 1m0s
[23:31:11.792] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:45:0)
[23:31:11.792] Timer tick, asking Buildbucket for the build status
[23:31:12.520] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:12.520] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:46:0) after 1m0s
[23:32:12.563] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:46:0)
[23:32:12.563] Timer tick, asking Buildbucket for the build status
[23:32:12.722] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:12.722] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:47:0) after 1m0s
[23:33:12.981] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:47:0)
[23:33:12.981] Timer tick, asking Buildbucket for the build status
[23:33:13.556] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:13.556] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:48:0) after 1m0s
[23:34:13.650] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:48:0)
[23:34:13.650] Timer tick, asking Buildbucket for the build status
[23:34:14.412] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:14.412] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:49:0) after 1m0s
[23:35:14.420] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:49:0)
[23:35:14.420] Timer tick, asking Buildbucket for the build status
[23:35:14.804] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:14.804] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:50:0) after 1m0s
[23:36:14.840] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:50:0)
[23:36:14.840] Timer tick, asking Buildbucket for the build status
[23:36:15.014] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:15.014] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:51:0) after 1m0s
[23:37:15.055] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:51:0)
[23:37:15.055] Timer tick, asking Buildbucket for the build status
[23:37:15.292] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:15.292] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:52:0) after 1m0s
[23:38:15.326] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:52:0)
[23:38:15.326] Timer tick, asking Buildbucket for the build status
[23:38:15.498] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:15.498] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:53:0) after 1m0s
[23:39:15.547] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:53:0)
[23:39:15.547] Timer tick, asking Buildbucket for the build status
[23:39:16.052] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:16.052] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:54:0) after 1m0s
[23:40:16.120] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:54:0)
[23:40:16.120] Timer tick, asking Buildbucket for the build status
[23:40:16.271] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:16.271] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:55:0) after 1m0s
[23:41:16.323] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:55:0)
[23:41:16.323] Timer tick, asking Buildbucket for the build status
[23:41:17.297] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:17.297] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:56:0) after 1m0s
[23:42:17.338] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:56:0)
[23:42:17.339] Timer tick, asking Buildbucket for the build status
[23:42:17.505] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:17.505] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:57:0) after 1m0s
[23:43:17.515] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:57:0)
[23:43:17.515] Timer tick, asking Buildbucket for the build status
[23:43:18.255] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:18.255] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:58:0) after 1m0s
[23:44:18.410] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:58:0)
[23:44:18.410] Timer tick, asking Buildbucket for the build status
[23:44:18.548] Build 8889164370421271104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:18.548] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054594435897229344:59:0) after 1m0s
[23:44:28.803] Received PubSub notification, asking Buildbucket for the build status
[23:44:29.716] Build 8889164370421271104: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:44:29.716] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1581032668007400",
"created_by": "project:chromium",
"created_ts": "1581029267823895",
"id": "8889164370421271104",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"96d1455e14c27e84bc6d85fec7bb116553093bcb\"}}",
"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-22-4tmk\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168316, \"got_angle_revision\": \"e9dc0201af3af167d21bc0f26bc763e18e82f994\", \"got_dawn_revision\": \"c30635174e125bda00771900089c34751e28faeb\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"96d1455e14c27e84bc6d85fec7bb116553093bcb\", \"got_revision_cp\": \"refs/heads/master@{#739145}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"dd48b7e2d06e74e1d20da5f43224b4ae99873e64\", \"got_v8_revision\": \"624f9f49e78ad57fc114a50d4d2a97ff3f25a841\", \"got_v8_revision_cp\": \"refs/heads/8.2.25@{#1}\", \"got_webrtc_revision\": \"4f68f5398d7fa3d47c449e99893c9bea07bf7ca2\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30473}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"96d1455e14c27e84bc6d85fec7bb116553093bcb\", \"swarm_hashes_refs/heads/master(at){#739145}_without_patch\": {\"accessibility_unittests\": \"b82c80f0abb44e870fdb0fb1c80e04e2d6a43169\", \"angle_unittests\": \"28b9bd0ac2824eba0688707bd99b1de1eb2884ae\", \"app_shell_unittests\": \"194e6c86b0020e7fc5f427d52c53679605549d7f\", \"aura_unittests\": \"165933162cdfa947671caca9988f6a8b9e9e6444\", \"base_unittests\": \"99a05db00a12907b0e90a07d760ad9e55827c661\", \"base_util_unittests\": \"906910cddb87f4755d82dd435d27747f6c1badb6\", \"blink_common_unittests\": \"3ddb79a1a14e4e20109533edaf1ebf2dccbf0f14\", \"blink_fuzzer_unittests\": \"ceefd1f70742a7d5ca40b95315663cad815c17f4\", \"blink_heap_unittests\": \"b3bb19a901217552eac77efd1403a866ce74eae2\", \"blink_platform_unittests\": \"6446cb681360f8475b40ad9226c39f5a90edceb1\", \"blink_python_tests\": \"3b5a799bfe7597d0d8ae495b3ca4a729c97b6135\", \"blink_unittests\": \"10475c14c91df5b7388cc9bbfd043503ea6215e1\", \"blink_web_tests\": \"c98811059ef3d78cb8cf99bde774c7bd4ff96546\", \"boringssl_crypto_tests\": \"a10a169aa28122b4122bdeb0f64c700c8bf46507\", \"boringssl_ssl_tests\": \"bc5cd69267a8515d9e847f2b03d2067fdabc86ca\", \"browser_tests\": \"aa8c2a7a655113c77593da85e45b7c9ecfb43c79\", \"cacheinvalidation_unittests\": \"f2964eee9064f6349c1800d9577e0d91db078200\", \"capture_unittests\": \"cedf173dd52b16b851215932cb7ddd662533df7c\", \"cast_unittests\": \"b5fe68ca916c17a437ce5cfe3775e372f2d47e23\", \"cc_unittests\": \"8ee720564e2c8076d8f4c05a5c2d979847450355\", \"chrome_app_unittests\": \"fc5825ec7748c0de4327f460b82d48e11670a416\", \"chromedriver_unittests\": \"89dace6234426277785d546b12d5231894b1b397\", \"components_browsertests\": \"0c7f9bf43e77428a1ec8ed5f0eb44cb8cf3ebeef\", \"components_unittests\": \"84502fef600cf87dc059921d1a9e2f9c42f0245a\", \"compositor_unittests\": \"afd13f54902384603dd102fde1e1e7d147ce1838\", \"content_browsertests\": \"32ff3fb077342f33c745906c20b43ce44b1d14a6\", \"content_shell_crash_test\": \"b78dfaeb7609768dc7f972be482d8ae10313129c\", \"content_unittests\": \"42895314b4629bb286fb8de34eef97a1c9dc887d\", \"crashpad_tests\": \"51b1d37b0fd48ea4473d84f994fb1d1db7c77bac\", \"cronet_tests\": \"7ce06cf42094dfc1f647fb4ec6036f19a388dd0e\", \"cronet_unittests\": \"c1e91daa72a92e844edefc88bdf1fc708be6e482\", \"crypto_unittests\": \"da018043f50d9d3b7a233d656e6b612f675fb53e\", \"dbus_unittests\": \"7950e48e51f7f1989f606f49f7843e1395ef832d\", \"device_unittests\": \"2e2a4371ea11bae97212a3db7b7a4e759372e474\", \"display_unittests\": \"059818ab185e9f50b14471cd1d6ae486a3118f50\", \"events_unittests\": \"8f2e200f3e0fc82e7c8bbd45aa24c88657e7badf\", \"extensions_browsertests\": \"a691c74af83d2d738ee1eae7338bdefdeb8ae31e\", \"extensions_unittests\": \"f6525e719d666a61e7937aebad2a12bc9d92775a\", \"filesystem_service_unittests\": \"0cf3d157d8a259c231e514645804148024469a34\", \"flatbuffers_unittests\": \"ca71348ce5934d53fb892115f8e6e8a2436d5e6c\", \"gcm_unit_tests\": \"9bb7ae8ee654bb2d5c09661c59f312a1556e8fab\", \"gfx_unittests\": \"601005ca61bc6b4c5f379afc1b6c5a8397667c3f\", \"gin_unittests\": \"6fe7b5385d64ac9afc5404fca67fea42cec989a4\", \"google_apis_unittests\": \"7f801f5519226475801a723d8d05beaa95e1a08d\", \"gpu_unittests\": \"c6fa5470abf4170191ff392759faee890edd077a\", \"grit_python_unittests\": \"f80982ecf83687fa6fb54f70c7f20c16fccfb4da\", \"gwp_asan_unittests\": \"d7fc741ea7c6bb3421d6034614fc16efa73dab04\", \"headless_browsertests\": \"6ea68f5bb2ab10329a41a5f1e633a8ee773cad6c\", \"headless_unittests\": \"0f2e7b5526bd080de534e6f556501eb582d8b344\", \"interactive_ui_tests\": \"01f86a32957f413f12caacd7ec3de02e9f134d08\", \"ipc_tests\": \"233ea27f2de7ae067c009d01eceaf11317bd8b35\", \"jingle_unittests\": \"165a3d8dcc357049b6add0733f364c991f8254e2\", \"latency_unittests\": \"c1063c95a7d34beefb24d37184107b1e0b1c3de4\", \"libjingle_xmpp_unittests\": \"5c29d44b245f72bfe6683e5eb7f2be682c8d58a4\", \"media_blink_unittests\": \"3c29d853b422d9e1f7d4ecc48408462feb8ddf9a\", \"media_service_unittests\": \"0a32dae773f60a17e211a6dc1a11484f8cc5bedf\", \"media_unittests\": \"7d7bbf1d81dc2bd06abfe70b64e65cbb7075caa2\", \"message_center_unittests\": \"345134c9dd9c7e315f62c8a4edf8aa5a4e49ee82\", \"metrics_python_tests\": \"2b069071dbcdd3c1d6d0e15e284d61105bc5d660\", \"midi_unittests\": \"48768a04acf50b187fca29d09a943e3649e58f79\", \"mojo_core_unittests\": \"aa18c7f183e2d8bdce03bd7e444209f6601b69d4\", \"mojo_unittests\": \"d4dbed6cbbc34b8220b04b670f8cad1931715c2f\", \"nacl_helper_nonsfi_unittests\": \"1245060376498636e2000814a0d299fcbc7fc830\", \"nacl_loader_unittests\": \"eddb6c53f43be0a46fac0a8df6ee04399eb9471d\", \"native_theme_unittests\": \"b906145fbcd2d049bf8a14e4045c28788b8f615b\", \"net_unittests\": \"b2e33bb64aa5373ef7daaaf761232a3bb3f0334b\", \"pdf_unittests\": \"d5d3600a84050a5068366403797bfb5d3ebd3b7e\", \"perfetto_unittests\": \"2201a21790dba4c3d09a32d21c7b388914345f20\", \"ppapi_unittests\": \"5373c2e4026fae2cdbe7dbc1ecd437ba98f739de\", \"printing_unittests\": \"9f1596f9c33fc37a240b12ca955886fcbc493187\", \"remoting_unittests\": \"0ba12808b0d772f1fb9cbf3b5a04b58933f56830\", \"sandbox_linux_unittests\": \"d89303ddbeb387c2412b01385e70a65d4e4ea8bd\", \"service_manager_unittests\": \"c92ca1b8d39e22433551b6a468270b0c474f95e2\", \"services_unittests\": \"8713fbed6e0b4a559aabb6b4cb373d66a140a008\", \"shell_dialogs_unittests\": \"b2e43755b4b945a213d4145f06bfddcce92d033d\", \"skia_unittests\": \"6997b40a128b41f9dfaa7d0a728cce2aac948b58\", \"snapshot_unittests\": \"addad9a189671dee819b44142b9a6657f31d6724\", \"sql_unittests\": \"1847f8f72ab7a055794377234dd1bd991fb3c652\", \"storage_unittests\": \"473dc82f68ff4295c9c7f9daef7e9799eae4167e\", \"sync_integration_tests\": \"e6abbcbea93b72935449aec9a66d848952cd07b8\", \"telemetry_gpu_unittests\": \"8aeb35417102d26cb04db43c35fdca37f83d5d33\", \"telemetry_perf_unittests\": \"633192785f7446795c39018e19166cf9ed2b85f4\", \"telemetry_unittests\": \"9817900b91bdc4858ab5d7f99f5ad5567bdd7924\", \"traffic_annotation_auditor_unittests\": \"36e660fbc2ecf08337ceaf6b7413d0640f310add\", \"ui_base_unittests\": \"492a30448e3c834f23116e939c06f970fae344c3\", \"ui_touch_selection_unittests\": \"007b7a21d9cbb63b5422b6f40822a9bfe12f47fd\", \"unit_tests\": \"922f20dc667125b7e026d30eda7404cac4253cae\", \"url_unittests\": \"63fc71c4c79689dc09e4d372cfa577e65ad3b90e\", \"views_perftests\": \"2991234bf28a5c007e38c31626959c863428d2d7\", \"views_unittests\": \"c45f3838202c9529986ed303937e6404a515df71\", \"viz_unittests\": \"1a7c7669fca96b0d6b077c47b26794bdea7f516a\", \"vr_common_unittests\": \"f5b06d27996884bcdce5c0fba4b7a474eeb7fbe8\", \"vr_pixeltests\": \"66fe0e21d8a5579ad5f65b468b7379bd2a2a2f7b\", \"webdriver_wpt_tests\": \"b7f0bd6dbf43d0f2aa922bec2eb65bfa976958eb\", \"weblayer_browsertests\": \"b3f16d334147cb331d565004d9e5432b9ea6f9f5\", \"wm_unittests\": \"66574ca7c69db97f41061295bd13047a7535198c\", \"wtf_unittests\": \"3452328183122d0f6c2eb05405206572a73c431f\", \"xr_browser_tests\": \"271abb4a7b474ce01922ba662c3889e442984289\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1989594bdf03467d4cca251563547d36d290a477ee0cdac91b5f17239ddd8c8b_v2\", \"builder_a3249012ab2e105b4f1a10f0dc352b422f692fd701d889292e5c6170d198db4b_v2\", \"builder_a8228d45a2e4046ab48e21b574ab916f6d3c7e4ad955b91040a9e9374d3dd18a_v2\", \"builder_aa203c62d40796be56862d3cd372584a979c4ed4042cc78e2db5125251ab240a_v2\", \"builder_b9373cfdcc5f43b5e07cc4931fc34027757a424c7183ae2b31ae65ec7718ca12_v2\", \"builder_d2010e0a6a440fb89731b061caf7d596c92723d209ffefd62489cc037db4f7a7_v2\", \"builder_fac2959e29eeee13eb43de2791ff2fcb7aa7ab15952f0beca21f4615b55fa2a2_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-22-4tmk\"], \"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": "1581029550184543",
"status": "COMPLETED",
"status_changed_ts": "1581032668411887",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168316",
"builder:Linux Builder (dbg)",
"buildset:commit/git/96d1455e14c27e84bc6d85fec7bb116553093bcb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/96d1455e14c27e84bc6d85fec7bb116553093bcb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054594435897229344",
"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/8889164370421271104/+/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:4a3581a4b010d210",
"user_agent:luci-scheduler"
],
"updated_ts": "1581032668416375",
"url": "https://ci.chromium.org/b/8889164370421271104",
"utcnow_ts": "1581032669701579"
}
}
[23:44:29.716] Invocation finished in 56m46.499390201s with status SUCCEEDED