[23:14:12.412] New invocation is queued and will start shortly
[23:14:13.725] Starting the invocation (attempt 1)
[23:14:13.762] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:14:13.763] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:14:13.763] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9054139784695631408",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"3c76ec62b5291426761f6e3809f6d835f50cd525\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9054139784695631408",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/3c76ec62b5291426761f6e3809f6d835f50cd525",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3c76ec62b5291426761f6e3809f6d835f50cd525",
"gitiles_ref:refs/heads/master"
]
}
[23:14:15.637] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1581462854369293",
"id": "8888709721975165216",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3c76ec62b5291426761f6e3809f6d835f50cd525\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1581462855354748",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168497",
"builder:Linux Builder (dbg)",
"buildset:commit/git/3c76ec62b5291426761f6e3809f6d835f50cd525",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3c76ec62b5291426761f6e3809f6d835f50cd525",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054139784695631408",
"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/8888709721975165216/+/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": "1581462855355479",
"url": "https://ci.chromium.org/b/8888709721975165216",
"utcnow_ts": "1581462855630405"
}
}
[23:14:15.637] Task URL: https://ci.chromium.org/b/8888709721975165216
[23:14:15.637] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:2:0) after 1m0s
[23:15:15.662] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:2:0)
[23:15:15.662] Timer tick, asking Buildbucket for the build status
[23:15:16.001] Build 8888709721975165216: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:15:16.001] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:3:0) after 1m0s
[23:16:16.040] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:3:0)
[23:16:16.040] Timer tick, asking Buildbucket for the build status
[23:16:17.004] Build 8888709721975165216: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:16:17.004] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:4:0) after 1m0s
[23:17:17.035] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:4:0)
[23:17:17.035] Timer tick, asking Buildbucket for the build status
[23:17:19.046] Build 8888709721975165216: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:17:19.046] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:5:0) after 1m0s
[23:18:19.117] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:5:0)
[23:18:19.117] Timer tick, asking Buildbucket for the build status
[23:18:19.611] Build 8888709721975165216: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:18:19.611] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:6:0) after 1m0s
[23:19:12.445] Received PubSub notification, asking Buildbucket for the build status
[23:19:12.603] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:19.635] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:6:0)
[23:19:19.635] Timer tick, asking Buildbucket for the build status
[23:19:20.232] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:20.232] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:8:0) after 1m0s
[23:20:20.292] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:8:0)
[23:20:20.292] Timer tick, asking Buildbucket for the build status
[23:20:20.417] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:20.417] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:9:0) after 1m0s
[23:21:20.436] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:9:0)
[23:21:20.436] Timer tick, asking Buildbucket for the build status
[23:21:20.745] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:20.745] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:10:0) after 1m0s
[23:22:20.777] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:10:0)
[23:22:20.777] Timer tick, asking Buildbucket for the build status
[23:22:21.178] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:21.178] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:11:0) after 1m0s
[23:23:21.221] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:11:0)
[23:23:21.221] Timer tick, asking Buildbucket for the build status
[23:23:21.663] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:21.663] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:12:0) after 1m0s
[23:24:21.701] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:12:0)
[23:24:21.701] Timer tick, asking Buildbucket for the build status
[23:24:22.024] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:22.024] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:13:0) after 1m0s
[23:25:22.112] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:13:0)
[23:25:22.112] Timer tick, asking Buildbucket for the build status
[23:25:22.281] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:22.281] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:14:0) after 1m0s
[23:26:22.280] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:14:0)
[23:26:22.280] Timer tick, asking Buildbucket for the build status
[23:26:23.385] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:23.385] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:15:0) after 1m0s
[23:27:23.452] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:15:0)
[23:27:23.452] Timer tick, asking Buildbucket for the build status
[23:27:24.092] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:24.092] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:16:0) after 1m0s
[23:28:24.090] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:16:0)
[23:28:24.090] Timer tick, asking Buildbucket for the build status
[23:28:24.982] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:24.982] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:17:0) after 1m0s
[23:29:25.062] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:17:0)
[23:29:25.062] Timer tick, asking Buildbucket for the build status
[23:29:25.527] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:25.527] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:18:0) after 1m0s
[23:30:25.586] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:18:0)
[23:30:25.586] Timer tick, asking Buildbucket for the build status
[23:30:26.077] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:26.077] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:19:0) after 1m0s
[23:31:26.147] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:19:0)
[23:31:26.147] Timer tick, asking Buildbucket for the build status
[23:31:26.306] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:26.306] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:20:0) after 1m0s
[23:32:26.345] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:20:0)
[23:32:26.345] Timer tick, asking Buildbucket for the build status
[23:32:26.472] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:26.472] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:21:0) after 1m0s
[23:33:26.584] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:21:0)
[23:33:26.584] Timer tick, asking Buildbucket for the build status
[23:33:28.257] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:28.257] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:22:0) after 1m0s
[23:34:28.243] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:22:0)
[23:34:28.243] Timer tick, asking Buildbucket for the build status
[23:34:28.684] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:28.684] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:23:0) after 1m0s
[23:35:28.876] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:23:0)
[23:35:28.876] Timer tick, asking Buildbucket for the build status
[23:35:29.185] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:29.185] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:24:0) after 1m0s
[23:36:29.228] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:24:0)
[23:36:29.228] Timer tick, asking Buildbucket for the build status
[23:36:29.459] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:29.459] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:25:0) after 1m0s
[23:37:29.477] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:25:0)
[23:37:29.477] Timer tick, asking Buildbucket for the build status
[23:37:29.649] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:29.649] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:26:0) after 1m0s
[23:38:29.644] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:26:0)
[23:38:29.644] Timer tick, asking Buildbucket for the build status
[23:38:29.811] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:29.812] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:27:0) after 1m0s
[23:39:29.832] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:27:0)
[23:39:29.832] Timer tick, asking Buildbucket for the build status
[23:39:30.477] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:30.477] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:28:0) after 1m0s
[23:40:30.599] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:28:0)
[23:40:30.599] Timer tick, asking Buildbucket for the build status
[23:40:31.328] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:31.328] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:29:0) after 1m0s
[23:41:31.562] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:29:0)
[23:41:31.562] Timer tick, asking Buildbucket for the build status
[23:41:31.797] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:31.797] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:30:0) after 1m0s
[23:42:31.960] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:30:0)
[23:42:31.960] Timer tick, asking Buildbucket for the build status
[23:42:32.131] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:32.131] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:31:0) after 1m0s
[23:43:32.257] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:31:0)
[23:43:32.257] Timer tick, asking Buildbucket for the build status
[23:43:32.856] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:32.856] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:32:0) after 1m0s
[23:44:33.002] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:32:0)
[23:44:33.002] Timer tick, asking Buildbucket for the build status
[23:44:33.267] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:33.267] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:33:0) after 1m0s
[23:45:33.483] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:33:0)
[23:45:33.483] Timer tick, asking Buildbucket for the build status
[23:45:34.205] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:34.205] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:34:0) after 1m0s
[23:46:34.463] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:34:0)
[23:46:34.463] Timer tick, asking Buildbucket for the build status
[23:46:35.026] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:35.026] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:35:0) after 1m0s
[23:47:35.206] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:35:0)
[23:47:35.206] Timer tick, asking Buildbucket for the build status
[23:47:35.399] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:35.399] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:36:0) after 1m0s
[23:48:35.591] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:36:0)
[23:48:35.591] Timer tick, asking Buildbucket for the build status
[23:48:35.869] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:35.869] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:37:0) after 1m0s
[23:49:35.998] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:37:0)
[23:49:35.998] Timer tick, asking Buildbucket for the build status
[23:49:36.230] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:36.230] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:38:0) after 1m0s
[23:50:36.354] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:38:0)
[23:50:36.354] Timer tick, asking Buildbucket for the build status
[23:50:36.509] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:36.509] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:39:0) after 1m0s
[23:51:36.606] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:39:0)
[23:51:36.606] Timer tick, asking Buildbucket for the build status
[23:51:37.879] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:37.879] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:40:0) after 1m0s
[23:52:37.950] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:40:0)
[23:52:37.950] Timer tick, asking Buildbucket for the build status
[23:52:38.289] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:38.289] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:41:0) after 1m0s
[23:53:38.352] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:41:0)
[23:53:38.352] Timer tick, asking Buildbucket for the build status
[23:53:38.637] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:38.638] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:42:0) after 1m0s
[23:54:38.683] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:42:0)
[23:54:38.683] Timer tick, asking Buildbucket for the build status
[23:54:39.306] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:39.306] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:43:0) after 1m0s
[23:55:39.345] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:43:0)
[23:55:39.345] Timer tick, asking Buildbucket for the build status
[23:55:39.521] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:39.521] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:44:0) after 1m0s
[23:56:39.542] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:44:0)
[23:56:39.542] Timer tick, asking Buildbucket for the build status
[23:56:39.735] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:39.735] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:45:0) after 1m0s
[23:57:39.838] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:45:0)
[23:57:39.838] Timer tick, asking Buildbucket for the build status
[23:57:40.035] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:40.035] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:46:0) after 1m0s
[23:58:40.059] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:46:0)
[23:58:40.059] Timer tick, asking Buildbucket for the build status
[23:58:40.231] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:40.231] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:47:0) after 1m0s
[23:59:40.288] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:47:0)
[23:59:40.288] Timer tick, asking Buildbucket for the build status
[23:59:40.422] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:40.422] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:48:0) after 1m0s
[00:00:40.474] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:48:0)
[00:00:40.474] Timer tick, asking Buildbucket for the build status
[00:00:40.656] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:40.656] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:49:0) after 1m0s
[00:01:40.675] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:49:0)
[00:01:40.675] Timer tick, asking Buildbucket for the build status
[00:01:40.875] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:40.875] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:50:0) after 1m0s
[00:02:40.899] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:50:0)
[00:02:40.899] Timer tick, asking Buildbucket for the build status
[00:02:41.595] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:41.595] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:51:0) after 1m0s
[00:03:41.587] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:51:0)
[00:03:41.587] Timer tick, asking Buildbucket for the build status
[00:03:42.072] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:42.072] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:52:0) after 1m0s
[00:04:42.098] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:52:0)
[00:04:42.098] Timer tick, asking Buildbucket for the build status
[00:04:43.073] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:43.073] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:53:0) after 1m0s
[00:05:43.232] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:53:0)
[00:05:43.232] Timer tick, asking Buildbucket for the build status
[00:05:43.481] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:43.481] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:54:0) after 1m0s
[00:06:43.554] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:54:0)
[00:06:43.554] Timer tick, asking Buildbucket for the build status
[00:06:44.340] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:44.340] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:55:0) after 1m0s
[00:07:44.350] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:55:0)
[00:07:44.350] Timer tick, asking Buildbucket for the build status
[00:07:44.527] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:44.527] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:56:0) after 1m0s
[00:08:44.553] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:56:0)
[00:08:44.553] Timer tick, asking Buildbucket for the build status
[00:08:44.963] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:44.963] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:57:0) after 1m0s
[00:09:45.069] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:57:0)
[00:09:45.069] Timer tick, asking Buildbucket for the build status
[00:09:45.509] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:45.509] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:58:0) after 1m0s
[00:10:45.569] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:58:0)
[00:10:45.569] Timer tick, asking Buildbucket for the build status
[00:10:46.622] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:46.622] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:59:0) after 1m0s
[00:11:46.723] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:59:0)
[00:11:46.723] Timer tick, asking Buildbucket for the build status
[00:11:46.871] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:46.871] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:60:0) after 1m0s
[00:12:46.893] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:60:0)
[00:12:46.893] Timer tick, asking Buildbucket for the build status
[00:12:47.057] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:47.057] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:61:0) after 1m0s
[00:13:47.171] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:61:0)
[00:13:47.171] Timer tick, asking Buildbucket for the build status
[00:13:47.349] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:47.349] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:62:0) after 1m0s
[00:14:47.490] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:62:0)
[00:14:47.490] Timer tick, asking Buildbucket for the build status
[00:14:47.668] Build 8888709721975165216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:47.669] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9054139784695631408:63:0) after 1m0s
[00:15:28.278] Received PubSub notification, asking Buildbucket for the build status
[00:15:28.396] Build 8888709721975165216: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:15:28.396] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1581466527560807",
"created_by": "project:chromium",
"created_ts": "1581462854369293",
"id": "8888709721975165216",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3c76ec62b5291426761f6e3809f6d835f50cd525\"}}",
"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-138-dz12\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168497, \"got_angle_revision\": \"b40d48a67c932625f349c6e58036c2d96b62489b\", \"got_dawn_revision\": \"2fd618192985122aafb494ac5e9a39f572ef46fc\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"3c76ec62b5291426761f6e3809f6d835f50cd525\", \"got_revision_cp\": \"refs/heads/master@{#740457}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"6cbb02e0dc934ac1e21b182f0f632b4e806449a6\", \"got_v8_revision\": \"56ab990e69987df228757938e23a9bafccab2fe5\", \"got_v8_revision_cp\": \"refs/heads/8.2.57@{#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\": \"3c76ec62b5291426761f6e3809f6d835f50cd525\", \"swarm_hashes_refs/heads/master(at){#740457}_without_patch\": {\"accessibility_unittests\": \"997a3d5332fb118d185c7ec4c3d1a9b3f49997cf\", \"angle_unittests\": \"a4a5bed26edb0c4312ee14ce6a51cbcdf24c72ad\", \"app_shell_unittests\": \"9b72859d7e98ebcb9f6b002e87589b059b4df0ec\", \"aura_unittests\": \"0007e27c03cc7ff42a2a27b0a4be24699c131a21\", \"base_unittests\": \"c7a4ce7d44ae42a3e85fa1b1bbd96895b4d672c6\", \"base_util_unittests\": \"74042a8d363256e8e45437af6b2b7cf667f3044c\", \"blink_common_unittests\": \"e0d0f9439e0f260e6f8d21eac2151e18215c280d\", \"blink_fuzzer_unittests\": \"756986d2bb0beae2307d34a99561fe343ef8ee46\", \"blink_heap_unittests\": \"15c26b9d9c6532da61185e3d34d51d7f096b417e\", \"blink_platform_unittests\": \"145b91ee871d3925fe0ae62a9baa6b02432272b5\", \"blink_python_tests\": \"cece33b28cc2b544abc39c5fb93fe4dc5b57507c\", \"blink_unittests\": \"71ebda73bf63e598406836fda237973a2ab8a1f0\", \"blink_web_tests\": \"a6cc75a35567e92284191209714d3706ee136646\", \"boringssl_crypto_tests\": \"60cfd6f6d968c11bf815a112934f52c9ba8acced\", \"boringssl_ssl_tests\": \"523f6d6b5a38170f8ae28820c3b6f7138adb87ba\", \"browser_tests\": \"7b75d3bec1e23f03cae47f1318fe43d5f4539740\", \"cacheinvalidation_unittests\": \"311698b85a7ef57e58bd5beb9cd18783e06bcafe\", \"capture_unittests\": \"d6342e697bfa70521654df92bc752711b50b058c\", \"cast_unittests\": \"ef28cbe790d8226cc7b6fc4c21723e61202b720c\", \"cc_unittests\": \"cc92879582dfa935e2a63bf9cee4de47cb466d0b\", \"chrome_app_unittests\": \"b768b22a5354f44ef921d58bb333e0f761fd4531\", \"chromedriver_unittests\": \"af2cd5ba290712bf38abbf7386377487549dc53a\", \"components_browsertests\": \"2fc5d6f16709502ac04cdad4fafc34ae541c4f73\", \"components_unittests\": \"156f26e08afe84bffe2f296b5f006a468c2e9848\", \"compositor_unittests\": \"ecafadafe526c5f4b949130080a7d0f50bacf6f6\", \"content_browsertests\": \"6729f7f077aaeedd6c94b22cb8126c857d0fe716\", \"content_shell_crash_test\": \"f87afffbe34022caeb3c595db0cfad4e719ebd52\", \"content_unittests\": \"486ad67624caea082e28ca86fe651e47d7b0fe55\", \"crashpad_tests\": \"e54076a009d63d4191413a4e782050416c2ac726\", \"cronet_tests\": \"244cee5571280069c979b5532bf2ba2183584047\", \"cronet_unittests\": \"2edaf00a0be659ccaf5b3a7923ff78c959b5a34d\", \"crypto_unittests\": \"bb6a24422c3c82d3892c2877204e1c1cf8c44227\", \"dbus_unittests\": \"a56018a2fd525afe17e30bec818920e6039b914e\", \"device_unittests\": \"8e4865fb08bbd61252fa6c6ee64f982a81aa73e7\", \"display_unittests\": \"c0b3eb2a030a51b6a3ef50e53801285858f1b7fd\", \"events_unittests\": \"b533f776c680b1d69c58e525a4824d2485f4eb5c\", \"extensions_browsertests\": \"ed33f95847d5b59b1d89c6c125934ae5a38c9283\", \"extensions_unittests\": \"6dbabd02be6f2c0d8180aa60816352e3256e5d9c\", \"filesystem_service_unittests\": \"6128808a6dbf5abb2128a44f76d74f513dc17cc5\", \"flatbuffers_unittests\": \"9eab69acab0f6bd1460710d9ac6e8475de73625c\", \"gcm_unit_tests\": \"5c12720e46b3f2e111cb48661864507a4925629c\", \"gfx_unittests\": \"ef576e6f727e61054709bd6a86cc100db76e0594\", \"gin_unittests\": \"5afab201d24e2fed93c955e31b5bf73cdaecac61\", \"google_apis_unittests\": \"93ab2e228d056c00a3cf99660e13ddd670a9bd67\", \"gpu_unittests\": \"91788718bd8a6df5c4b1048a4236875b5d249161\", \"grit_python_unittests\": \"07488c096d20bb2a4e6d9280813fca6afbee6dc0\", \"gwp_asan_unittests\": \"0a44c745c4606a3e2565f247bbc6eb332d6034a5\", \"headless_browsertests\": \"355ced9f553ff3dd5681689f8bfa8fa1039e23a3\", \"headless_unittests\": \"522bfe601949bd2968b7a01089e4eae7074bfc2b\", \"interactive_ui_tests\": \"9ff96179ca147cb94b77555dc9337a963aaeff0a\", \"ipc_tests\": \"3972c1412ee83076ab02ca4b050a42146eed7ab7\", \"jingle_unittests\": \"5eef32a724e5094546fc8b46f7aa2ef163e2d00b\", \"latency_unittests\": \"1ef1d744b8920b33d4e7bd0e1702df0b6a380789\", \"libjingle_xmpp_unittests\": \"83eecb36bca3f9e7c7e5b0da7440c24c6aad6e5a\", \"media_blink_unittests\": \"3017ac1c955790f70a6e003705d4c18f03235e5e\", \"media_service_unittests\": \"eca08f0aa2ac8ac83069774c55395ed525112048\", \"media_unittests\": \"3e006da7617ef613ed0929ebe46e5327b341dc27\", \"message_center_unittests\": \"d3f96877c8eec7b674e9542b660932aa7e558f0d\", \"metrics_python_tests\": \"ec8e16cebffebfb4c6203a2c2bd8dc0e6db682c8\", \"midi_unittests\": \"6bf1dc599164c619c52c48cb48b5fbe09b400c4e\", \"mojo_core_unittests\": \"8d41e070d6e38fd76ffd1deae3d5307b51a5a6a3\", \"mojo_unittests\": \"bdbf4c84510d7bce85630c583d54a9b0ebf2dbbb\", \"nacl_helper_nonsfi_unittests\": \"435a37c530c2fed9373c89fc3d2c90b6efce927b\", \"nacl_loader_unittests\": \"d2adf7505a42ce72e68ba1dd9936a028fcb8edf7\", \"native_theme_unittests\": \"fc9e89bbe2984640477b7d464005118763c81263\", \"net_unittests\": \"435d4e1fafb146d708676098d26e8c5106339d0c\", \"pdf_unittests\": \"ce539463c7fb1781e4aebc6d953f444bbe56b794\", \"perfetto_unittests\": \"6eaa08f45edabb7a5c9b1673e0eee6cdba9aa7fc\", \"ppapi_unittests\": \"621f36f83fc146abd6504a4bb738eff8b87d1f10\", \"printing_unittests\": \"79669b437d62fae3c250a4c883313f3a267c92bd\", \"remoting_unittests\": \"72790f1df283e1a5c5ef3040bb1a03c9777af5e6\", \"sandbox_linux_unittests\": \"3d117347cefae28a408b5c2645847fd4c5641aa7\", \"service_manager_unittests\": \"781510503b399e5de741df0f24cfb2b245ccb60a\", \"services_unittests\": \"7cf22c174708c0078eb60fa2bc2a43416b57dd83\", \"shell_dialogs_unittests\": \"6d4d1da80a31d850ddd55de435a2be88ea1cb507\", \"skia_unittests\": \"7010fb15a002b0ef417800283c79bde3f125a7da\", \"snapshot_unittests\": \"639c0962bb0457601d8b83a99b854104d548896a\", \"sql_unittests\": \"30998c2d90f45e29abb7c0b04578062934851a99\", \"storage_unittests\": \"51f553bafad68c9db577c12a3155f352c04db891\", \"sync_integration_tests\": \"94763f90685ffb0fbb5c2774358c5e8cb951ab71\", \"telemetry_gpu_unittests\": \"840a0c6cfca56f77d91500aa19b4bf1e0a9aab30\", \"telemetry_perf_unittests\": \"7cff0eca2f44eff7e53260521920c9ecd14cca21\", \"telemetry_unittests\": \"359d163b2edc88b2ae5d7d5b999432e40674662d\", \"traffic_annotation_auditor_unittests\": \"23173584ecc17ca402ddfc06995b1beed352e769\", \"ui_base_unittests\": \"7f4e10fbb249a106f1cd8dfd3e281a8458a29fb5\", \"ui_touch_selection_unittests\": \"b387f9f85cc40ca1141269c2ae53745a946080b0\", \"unit_tests\": \"14e4e16c952f01e7168d354087a55585fd7d6c69\", \"url_unittests\": \"914caa414937cdeb32952b5296b686f6ee0e9f90\", \"views_perftests\": \"6ba748700e4093850e270412d123d4ce4cc024e6\", \"views_unittests\": \"557c6fd1952a47e34f8496f2bef41ce24a13f7cb\", \"viz_unittests\": \"18e70c92bb399f18ad05d3fd0b7982b04649429c\", \"vr_common_unittests\": \"df67d6592e1d7b2c9537867ca16f39070e7f21dd\", \"vr_pixeltests\": \"8d9ba8e91164302549b10576fdbdb8b2b4369627\", \"webdriver_wpt_tests\": \"e0f2e7b33332c52fea27359e9f042a4eb2ffa448\", \"weblayer_browsertests\": \"7a9c2566c167558ff9eeaf418ad81ff5a768dbf4\", \"wm_unittests\": \"087b069efb7243a3524f0120e2b9b4e771914e1b\", \"wtf_unittests\": \"e36ada7e9a2e6c7b1dd89ff26e193f6c6a202bfb\", \"xr_browser_tests\": \"29477271c943d8d2fc24538d83c7b1bb0e155660\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1989594bdf03467d4cca251563547d36d290a477ee0cdac91b5f17239ddd8c8b_v2\", \"builder_2fce50e69d80f7d3cf1b01087d63cb5fa68f8d968b73601af44a2f0cc34bc410_v2\", \"builder_4f073cad989671df25347297d55a43b2baed7923c7c8febe8eed874750cd540a_v2\", \"builder_7a9f94695aa83f7478015e01762e75301e67c95c681ec0592232bd343861e191_v2\", \"builder_7e09b81c8d06479eaec2630704ae544ecbf4fef920ed2a3aec656c848d7a04d5_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-138-dz12\"], \"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-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1581463151358132",
"status": "COMPLETED",
"status_changed_ts": "1581466527871273",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168497",
"builder:Linux Builder (dbg)",
"buildset:commit/git/3c76ec62b5291426761f6e3809f6d835f50cd525",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3c76ec62b5291426761f6e3809f6d835f50cd525",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9054139784695631408",
"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/8888709721975165216/+/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:4a4f59a912dfa510",
"user_agent:luci-scheduler"
],
"updated_ts": "1581466527875623",
"url": "https://ci.chromium.org/b/8888709721975165216",
"utcnow_ts": "1581466528385211"
}
}
[00:15:28.396] Invocation finished in 1h1m16.005481989s with status SUCCEEDED