[10:38:28.662] New invocation is queued and will start shortly
[10:38:30.237] Starting the invocation (attempt 1)
[10:38:30.276] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:38:30.276] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:38:30.276] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9053462555331100160",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"ef8dbf914891497f3b40b14d2be87131e15471a1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9053462555331100160",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/ef8dbf914891497f3b40b14d2be87131e15471a1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ef8dbf914891497f3b40b14d2be87131e15471a1",
"gitiles_ref:refs/heads/master"
]
}
[10:38:31.113] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1582108710395143",
"id": "8888032492847008048",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ef8dbf914891497f3b40b14d2be87131e15471a1\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1582108710913738",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168786",
"builder:Linux Builder (dbg)",
"buildset:commit/git/ef8dbf914891497f3b40b14d2be87131e15471a1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ef8dbf914891497f3b40b14d2be87131e15471a1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053462555331100160",
"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/8888032492847008048/+/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": "1582108710913863",
"url": "https://ci.chromium.org/b/8888032492847008048",
"utcnow_ts": "1582108711101870"
}
}
[10:38:31.113] Task URL: https://ci.chromium.org/b/8888032492847008048
[10:38:31.113] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:2:0) after 1m0s
[10:39:31.142] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:2:0)
[10:39:31.142] Timer tick, asking Buildbucket for the build status
[10:39:31.307] Build 8888032492847008048: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:39:31.308] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:3:0) after 1m0s
[10:40:31.418] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:3:0)
[10:40:31.418] Timer tick, asking Buildbucket for the build status
[10:40:31.664] Build 8888032492847008048: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:40:31.664] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:4:0) after 1m0s
[10:41:31.856] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:4:0)
[10:41:31.856] Timer tick, asking Buildbucket for the build status
[10:41:31.958] Build 8888032492847008048: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:41:31.958] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:5:0) after 1m0s
[10:42:32.134] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:5:0)
[10:42:32.134] Timer tick, asking Buildbucket for the build status
[10:42:32.308] Build 8888032492847008048: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:42:32.308] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:6:0) after 1m0s
[10:43:08.299] Received PubSub notification, asking Buildbucket for the build status
[10:43:08.461] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:32.408] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:6:0)
[10:43:32.408] Timer tick, asking Buildbucket for the build status
[10:43:32.495] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:32.495] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:8:0) after 1m0s
[10:44:32.877] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:8:0)
[10:44:32.877] Timer tick, asking Buildbucket for the build status
[10:44:33.033] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:33.033] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:9:0) after 1m0s
[10:45:33.087] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:9:0)
[10:45:33.087] Timer tick, asking Buildbucket for the build status
[10:45:33.201] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:33.202] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:10:0) after 1m0s
[10:46:33.194] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:10:0)
[10:46:33.194] Timer tick, asking Buildbucket for the build status
[10:46:33.348] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:33.348] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:11:0) after 1m0s
[10:47:33.436] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:11:0)
[10:47:33.436] Timer tick, asking Buildbucket for the build status
[10:47:33.566] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:33.566] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:12:0) after 1m0s
[10:48:33.599] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:12:0)
[10:48:33.600] Timer tick, asking Buildbucket for the build status
[10:48:33.803] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:33.803] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:13:0) after 1m0s
[10:49:33.925] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:13:0)
[10:49:33.925] Timer tick, asking Buildbucket for the build status
[10:49:34.161] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:34.161] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:14:0) after 1m0s
[10:50:34.176] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:14:0)
[10:50:34.176] Timer tick, asking Buildbucket for the build status
[10:50:34.269] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:34.269] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:15:0) after 1m0s
[10:51:34.370] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:15:0)
[10:51:34.370] Timer tick, asking Buildbucket for the build status
[10:51:34.546] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:34.546] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:16:0) after 1m0s
[10:52:34.603] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:16:0)
[10:52:34.603] Timer tick, asking Buildbucket for the build status
[10:52:34.784] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:34.784] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:17:0) after 1m0s
[10:53:34.805] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:17:0)
[10:53:34.805] Timer tick, asking Buildbucket for the build status
[10:53:35.034] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:35.034] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:18:0) after 1m0s
[10:54:35.075] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:18:0)
[10:54:35.075] Timer tick, asking Buildbucket for the build status
[10:54:35.485] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:35.485] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:19:0) after 1m0s
[10:55:35.489] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:19:0)
[10:55:35.489] Timer tick, asking Buildbucket for the build status
[10:55:35.590] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:35.590] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:20:0) after 1m0s
[10:56:35.658] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:20:0)
[10:56:35.658] Timer tick, asking Buildbucket for the build status
[10:56:35.803] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:35.803] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:21:0) after 1m0s
[10:57:35.909] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:21:0)
[10:57:35.909] Timer tick, asking Buildbucket for the build status
[10:57:36.017] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:36.017] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:22:0) after 1m0s
[10:58:36.107] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:22:0)
[10:58:36.107] Timer tick, asking Buildbucket for the build status
[10:58:36.388] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:36.388] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:23:0) after 1m0s
[10:59:36.418] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:23:0)
[10:59:36.418] Timer tick, asking Buildbucket for the build status
[10:59:36.639] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:36.639] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:24:0) after 1m0s
[11:00:36.709] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:24:0)
[11:00:36.709] Timer tick, asking Buildbucket for the build status
[11:00:36.854] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:36.854] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:25:0) after 1m0s
[11:01:36.874] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:25:0)
[11:01:36.874] Timer tick, asking Buildbucket for the build status
[11:01:37.138] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:37.138] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:26:0) after 1m0s
[11:02:37.164] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:26:0)
[11:02:37.164] Timer tick, asking Buildbucket for the build status
[11:02:37.381] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:37.382] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:27:0) after 1m0s
[11:03:37.406] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:27:0)
[11:03:37.406] Timer tick, asking Buildbucket for the build status
[11:03:37.517] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:37.517] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:28:0) after 1m0s
[11:04:37.524] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:28:0)
[11:04:37.524] Timer tick, asking Buildbucket for the build status
[11:04:37.633] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:37.633] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:29:0) after 1m0s
[11:05:37.685] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:29:0)
[11:05:37.685] Timer tick, asking Buildbucket for the build status
[11:05:37.793] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:37.793] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:30:0) after 1m0s
[11:06:37.847] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:30:0)
[11:06:37.847] Timer tick, asking Buildbucket for the build status
[11:06:37.941] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:37.941] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:31:0) after 1m0s
[11:07:38.035] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:31:0)
[11:07:38.035] Timer tick, asking Buildbucket for the build status
[11:07:38.158] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:38.159] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:32:0) after 1m0s
[11:08:38.323] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:32:0)
[11:08:38.324] Timer tick, asking Buildbucket for the build status
[11:08:38.455] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:38.455] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:33:0) after 1m0s
[11:09:38.587] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:33:0)
[11:09:38.587] Timer tick, asking Buildbucket for the build status
[11:09:38.709] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:38.709] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:34:0) after 1m0s
[11:10:38.684] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:34:0)
[11:10:38.684] Timer tick, asking Buildbucket for the build status
[11:10:38.839] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:38.839] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:35:0) after 1m0s
[11:11:38.914] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:35:0)
[11:11:38.914] Timer tick, asking Buildbucket for the build status
[11:11:39.072] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:39.072] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:36:0) after 1m0s
[11:12:39.159] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:36:0)
[11:12:39.159] Timer tick, asking Buildbucket for the build status
[11:12:39.519] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:39.519] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:37:0) after 1m0s
[11:13:39.553] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:37:0)
[11:13:39.553] Timer tick, asking Buildbucket for the build status
[11:13:40.103] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:40.103] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:38:0) after 1m0s
[11:14:40.226] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:38:0)
[11:14:40.226] Timer tick, asking Buildbucket for the build status
[11:14:40.349] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:40.349] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:39:0) after 1m0s
[11:15:40.436] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:39:0)
[11:15:40.436] Timer tick, asking Buildbucket for the build status
[11:15:40.636] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:40.636] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:40:0) after 1m0s
[11:16:40.737] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:40:0)
[11:16:40.737] Timer tick, asking Buildbucket for the build status
[11:16:40.833] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:40.833] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:41:0) after 1m0s
[11:17:40.866] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:41:0)
[11:17:40.866] Timer tick, asking Buildbucket for the build status
[11:17:41.073] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:41.073] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:42:0) after 1m0s
[11:18:41.173] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:42:0)
[11:18:41.173] Timer tick, asking Buildbucket for the build status
[11:18:42.067] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:42.067] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:43:0) after 1m0s
[11:19:42.065] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:43:0)
[11:19:42.065] Timer tick, asking Buildbucket for the build status
[11:19:42.162] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:42.162] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:44:0) after 1m0s
[11:20:42.271] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:44:0)
[11:20:42.271] Timer tick, asking Buildbucket for the build status
[11:20:42.394] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:42.394] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:45:0) after 1m0s
[11:21:42.543] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:45:0)
[11:21:42.543] Timer tick, asking Buildbucket for the build status
[11:21:42.684] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:42.684] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:46:0) after 1m0s
[11:22:42.802] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:46:0)
[11:22:42.802] Timer tick, asking Buildbucket for the build status
[11:22:43.009] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:43.009] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:47:0) after 1m0s
[11:23:43.199] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:47:0)
[11:23:43.200] Timer tick, asking Buildbucket for the build status
[11:23:43.376] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:43.376] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:48:0) after 1m0s
[11:24:43.395] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:48:0)
[11:24:43.395] Timer tick, asking Buildbucket for the build status
[11:24:43.508] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:43.508] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:49:0) after 1m0s
[11:25:43.566] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:49:0)
[11:25:43.566] Timer tick, asking Buildbucket for the build status
[11:25:43.690] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:43.690] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:50:0) after 1m0s
[11:26:43.747] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:50:0)
[11:26:43.747] Timer tick, asking Buildbucket for the build status
[11:26:43.956] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:43.956] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:51:0) after 1m0s
[11:27:43.964] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:51:0)
[11:27:43.964] Timer tick, asking Buildbucket for the build status
[11:27:44.196] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:44.196] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:52:0) after 1m0s
[11:28:44.230] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:52:0)
[11:28:44.230] Timer tick, asking Buildbucket for the build status
[11:28:44.410] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:44.410] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:53:0) after 1m0s
[11:29:44.437] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:53:0)
[11:29:44.437] Timer tick, asking Buildbucket for the build status
[11:29:44.659] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:44.659] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:54:0) after 1m0s
[11:30:44.678] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:54:0)
[11:30:44.678] Timer tick, asking Buildbucket for the build status
[11:30:44.841] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:44.841] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:55:0) after 1m0s
[11:31:44.862] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:55:0)
[11:31:44.862] Timer tick, asking Buildbucket for the build status
[11:31:45.012] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:45.012] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:56:0) after 1m0s
[11:32:45.034] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:56:0)
[11:32:45.034] Timer tick, asking Buildbucket for the build status
[11:32:45.399] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:45.399] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:57:0) after 1m0s
[11:33:45.513] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:57:0)
[11:33:45.513] Timer tick, asking Buildbucket for the build status
[11:33:45.692] Build 8888032492847008048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:45.692] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053462555331100160:58:0) after 1m0s
[11:34:16.139] Received PubSub notification, asking Buildbucket for the build status
[11:34:16.213] Build 8888032492847008048: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:34:16.213] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1582112055525452",
"created_by": "project:chromium",
"created_ts": "1582108710395143",
"id": "8888032492847008048",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ef8dbf914891497f3b40b14d2be87131e15471a1\"}}",
"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-32-35uh\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168786, \"got_angle_revision\": \"fa9eff37c38ac16927bc1a85cf3d570c1c8e5183\", \"got_dawn_revision\": \"0aa86a7ae8315f93f9501323dc082af0ac869efc\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"ef8dbf914891497f3b40b14d2be87131e15471a1\", \"got_revision_cp\": \"refs/heads/master@{#742549}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"8def9063c4fdefa8e644198d2254ef279952c88b\", \"got_v8_revision\": \"8962e435a6bc325c8443aa9779596a51eb40c523\", \"got_v8_revision_cp\": \"refs/heads/8.2.116@{#1}\", \"got_webrtc_revision\": \"1282babe6621d3a2084bbd24e77652e0b3a4eb93\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30549}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"ef8dbf914891497f3b40b14d2be87131e15471a1\", \"swarm_hashes_refs/heads/master(at){#742549}_without_patch\": {\"accessibility_unittests\": \"01dfe52b38de6cb26b856c3624dac6c4e25bd4ed\", \"angle_unittests\": \"3fd6f81cda43eb791acdd082f67b5dc956115a0f\", \"app_shell_unittests\": \"7708b760ac607ea47c55c1819f42ae9e7351ce4a\", \"aura_unittests\": \"3788816ab0ba9d8547cc0bd8800aec1cbb6a19b8\", \"base_unittests\": \"ca364ea7164cbd159c67ddb4d314a062e5788f33\", \"base_util_unittests\": \"468deb0cf39f9fef5e3d18baa5a8609b86f47b57\", \"blink_common_unittests\": \"0cb172d51342b62cb8897afe456eba7aafd9e449\", \"blink_fuzzer_unittests\": \"a6865ab9c21b2640b9f7ad21b8d15a84f49a2cb1\", \"blink_heap_unittests\": \"c9d4a90d09666e20853645cbddd041eb811b6c2e\", \"blink_platform_unittests\": \"413825d308957ec8ba2a82b53527217645f7fa86\", \"blink_python_tests\": \"5617386b87b63f2a916285149851b5371f5bfc21\", \"blink_unittests\": \"d47aae1dc869b95706a804302938c0c574d2cbf1\", \"blink_web_tests\": \"3e8e04dfd83deef95763f1e221b41e3bf2f46f57\", \"boringssl_crypto_tests\": \"9ca2279dbeb2756495ac34cf237e59358588fb48\", \"boringssl_ssl_tests\": \"53725b9d83fa01fe5ec43a0332dfbd8f52c4fb60\", \"browser_tests\": \"b1f3746f3b0b0145abc979bd110c90ac8a327b10\", \"cacheinvalidation_unittests\": \"323e1e46995659bd764637a2e9c566a75979b25a\", \"capture_unittests\": \"d1c8c75ed3f71e106e54a56aa94cc1ffbf75b16b\", \"cast_unittests\": \"977351d21981693b9f7c9da923cdaccaa93250a3\", \"cc_unittests\": \"e1d7722f30a249a22cb0c3013dcdd6f716d06b7b\", \"chrome_app_unittests\": \"5baafa61e0a8f56144acca989e29569019f7d1f4\", \"chromedriver_unittests\": \"6cf17ac2b922b0a04735cf6a1ba8f5d62b09ce4d\", \"components_browsertests\": \"d1c340599f826408d0479b6a0118f6419a1baf1b\", \"components_unittests\": \"0776b9ba70d75218233fc4efa4699964cf7956d8\", \"compositor_unittests\": \"593768bd2d76c26d419987edb812e17a473aee3f\", \"content_browsertests\": \"a0ee08b32f6af538806a45bcce03ff0ec32086ee\", \"content_shell_crash_test\": \"11359b2cd58cd8a851272b5c86150ffe15afb6e6\", \"content_unittests\": \"17d18173270589e9419e2a83617bbdad30faed5e\", \"crashpad_tests\": \"cac984c945cbf6f7dc7781448e6b591e2483edc7\", \"cronet_tests\": \"61d49007ab6ce5e56d35bc9f544dfd9eabc1a8d9\", \"cronet_unittests\": \"8aa96970038660c0b0fac17db8753add57ae76d2\", \"crypto_unittests\": \"ab56a15fa4e09b3c0b965c33675a2577453d78bf\", \"dbus_unittests\": \"84c4dff3925f67cd30fa0a97555595f347a67ca8\", \"device_unittests\": \"e10708c4481d69fd780aaf3828ddbffe77b5b866\", \"display_unittests\": \"3506bbece6f1f30dd3182aa3b9ba1764d28c96ab\", \"events_unittests\": \"b33cb9ce0e62d0289e9fd9002e486eb2496e27f2\", \"extensions_browsertests\": \"95b99d7046a9c711c21fb710c16ffdf43f912c66\", \"extensions_unittests\": \"d6d01885bd58b6358edb1d77dbabb36b2ef40a98\", \"filesystem_service_unittests\": \"204b18ef90932d409b470ffd16ad02c69680999c\", \"flatbuffers_unittests\": \"9eab69acab0f6bd1460710d9ac6e8475de73625c\", \"gcm_unit_tests\": \"acb677873a2090f1a42ce5cdd178b1cabf7c3742\", \"gfx_unittests\": \"03d407be46c9049e4d79d040d68d32d16678687d\", \"gin_unittests\": \"26192c30328ac70d02892c93172ad1b9c17f082a\", \"google_apis_unittests\": \"5221c5b751ae003f0fa988979b534772c3812800\", \"gpu_unittests\": \"7a2ccce557e79c183c753c38bc4e96639540990e\", \"grit_python_unittests\": \"0a186e54539d3475bae4b9d19cc134d9a92e2108\", \"gwp_asan_unittests\": \"07223e118d9ad92e1d3a8c532b700b0a720fed09\", \"headless_browsertests\": \"dc4e301cd022e26635a0b76617f0ee8facd04423\", \"headless_unittests\": \"e69164aacefc3e69359f441f3ccbd3760b420548\", \"interactive_ui_tests\": \"c478dff9fc1df66af31b49f4ce019721ad3e74af\", \"ipc_tests\": \"847f100249d3b829734c67c475b96751a9a82789\", \"jingle_unittests\": \"9e91c4188aab633561d1849a1bd02f47071c697f\", \"latency_unittests\": \"fd4a1741a206f0ce98921d316f88b61f25f2c24e\", \"libjingle_xmpp_unittests\": \"06c22f4d1a639c5bd8010ec17eaec4802da14d07\", \"media_blink_unittests\": \"0b9eb17a46a789dcda42ed67da794ceb03bc989d\", \"media_service_unittests\": \"5ae599fb5b38b8741269e35a0c0e318fb80c6601\", \"media_unittests\": \"052ace9f126b4f27e9ed9efd4532a6dd5b5a30f8\", \"message_center_unittests\": \"63feda6c982caf2511cc9a7acf4f5af79219006b\", \"metrics_python_tests\": \"c661c6bf42694be262be2ee8d94a9b8a92438573\", \"midi_unittests\": \"97e10c14c72b1787aecae911313ee20124128881\", \"mojo_core_unittests\": \"d2afda697258ebc3c22b885bf6c18188593f39e1\", \"mojo_unittests\": \"ca2cdcb38b8cec74d153605f8957fa27a49d385e\", \"nacl_helper_nonsfi_unittests\": \"9dad0cdf1df497baf07ecef7be5eb9bb122cbbc4\", \"nacl_loader_unittests\": \"e68fd3e0e3c595e0bb31a0879e2d1b2f653e7509\", \"native_theme_unittests\": \"b03604a80423e24ea5fcadadf1d0fa63f639cd38\", \"net_unittests\": \"22b7c47105d22a71841cc09d22b5e696656a9134\", \"pdf_unittests\": \"ef8bd03541efb4b8056478c7cb97db65edbf97aa\", \"perfetto_unittests\": \"16a1aa16394f7d0420398f8c436b16de89b9bb66\", \"ppapi_unittests\": \"e3b623b83db891c1946df8fc52f079717a39ac54\", \"printing_unittests\": \"55473d73f133ca18d216cca7a43c5abef409cd29\", \"remoting_unittests\": \"8f242c9d05088c2769e1c377483fa2d1108d0f50\", \"sandbox_linux_unittests\": \"49333a7bacfab78ba5f30b5b83a585b4aba61dcc\", \"service_manager_unittests\": \"dd4dd6c2dfbf56e647e7d9ff547502724c9ab3c6\", \"services_unittests\": \"54ec7a346f4b4a6a7dedb100eed16b477d3df223\", \"shell_dialogs_unittests\": \"e6716bcce00201dbc5536bc61a502dc9141d38f0\", \"skia_unittests\": \"c72ee31dbe0c6b282425e49397a6b9d3e74826b6\", \"snapshot_unittests\": \"163e75ef32353c4cca73fceb7c95751a7137c1dd\", \"sql_unittests\": \"17a985241baacb73b9c0b748806301ff4e7e3f64\", \"storage_unittests\": \"84b34cc8db3e5b0e08105c312b002f41657bac64\", \"sync_integration_tests\": \"39bc9a7aff669a50edc81c1e779f210903e24342\", \"telemetry_gpu_unittests\": \"259b34ea404cbdc97c71761f27c69190edf92143\", \"telemetry_perf_unittests\": \"557b35a1a0bf7de47969d9cb361e403ef11ebf7f\", \"telemetry_unittests\": \"17f4197ebaafa6cc9624ed9836c8442ae1665fd3\", \"traffic_annotation_auditor_unittests\": \"4af659be5115c836d5e56cf2a1936014f59db766\", \"ui_base_unittests\": \"ec0a03809b99c021f9570947483ea8cd20f64613\", \"ui_touch_selection_unittests\": \"ce04139aeadf6bb9426b08f6f7561778f52fdd30\", \"unit_tests\": \"3fb2d393297fb8f997cc99dc18bebe7583366dfb\", \"url_unittests\": \"a36bf0f471ec21c9ce5719bb3d3bee1516acc535\", \"views_perftests\": \"35b418e1760dce38fe420d6d2cadde39c3fafb6e\", \"views_unittests\": \"7b0005f48ad6a2358e869df0b41bd057fb87b5b0\", \"viz_unittests\": \"3e2b127c4c802cd06a58c35c1cac0c58e98502cf\", \"vr_common_unittests\": \"47fa62c3f0705c1b90bca83760a082fb10538702\", \"vr_pixeltests\": \"58f633c16506c717d7c4bacc498c5f7bdc832f42\", \"webdriver_wpt_tests\": \"8b430ebe35c09f9b6426914131f171ccbf30ec25\", \"weblayer_browsertests\": \"3870769c2f2962ec3c1065e241db27054cf0a4c8\", \"wm_unittests\": \"7eed078e40961b279fd89e745ddce970c741ace2\", \"wtf_unittests\": \"951e5c551367a72d7c4f2c6d724169aca1bf1678\", \"xr_browser_tests\": \"bd2645ac249b1804ec6b234c9fd5f83e3ac6bb6b\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_5018db700e530f8e7ae860b5b3feab2b02d3dedf1bbd3e704c6c59fde1b308dd_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-32-35uh\"], \"image\": [\"chrome-xenial-20012600-8e1a7e485b7\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4825-3d129d6\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1582108987337761",
"status": "COMPLETED",
"status_changed_ts": "1582112055827341",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168786",
"builder:Linux Builder (dbg)",
"buildset:commit/git/ef8dbf914891497f3b40b14d2be87131e15471a1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ef8dbf914891497f3b40b14d2be87131e15471a1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053462555331100160",
"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/8888032492847008048/+/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:4a75d8a1de6f2610",
"user_agent:luci-scheduler"
],
"updated_ts": "1582112055830659",
"url": "https://ci.chromium.org/b/8888032492847008048",
"utcnow_ts": "1582112056204099"
}
}
[11:34:16.213] Invocation finished in 55m47.570008511s with status SUCCEEDED