[17:35:28.783] New invocation is queued and will start shortly
[17:35:30.905] Starting the invocation (attempt 1)
[17:35:30.944] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:35:30.944] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:35:30.944] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9053617513767409776",
"parameters_json": "{\"builder_name\":\"Linux Builder (dbg)\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4fe2b8c1fe705987af172bf3380227155154f23a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Linux Builder (dbg)",
"scheduler_invocation_id:9053617513767409776",
"scheduler_job_id:chromium/Linux Builder (dbg)",
"user_agent:luci-scheduler",
"buildset:commit/git/4fe2b8c1fe705987af172bf3380227155154f23a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4fe2b8c1fe705987af172bf3380227155154f23a",
"gitiles_ref:refs/heads/master"
]
}
[17:35:31.904] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1581960931073125",
"id": "8888187450697848816",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4fe2b8c1fe705987af172bf3380227155154f23a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1581960931676819",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168711",
"builder:Linux Builder (dbg)",
"buildset:commit/git/4fe2b8c1fe705987af172bf3380227155154f23a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4fe2b8c1fe705987af172bf3380227155154f23a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053617513767409776",
"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/8888187450697848816/+/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": "1581960931677116",
"url": "https://ci.chromium.org/b/8888187450697848816",
"utcnow_ts": "1581960931890775"
}
}
[17:35:31.904] Task URL: https://ci.chromium.org/b/8888187450697848816
[17:35:31.904] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:2:0) after 1m0s
[17:36:32.227] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:2:0)
[17:36:32.227] Timer tick, asking Buildbucket for the build status
[17:36:32.323] Build 8888187450697848816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:36:32.323] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:3:0) after 1m0s
[17:37:32.468] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:3:0)
[17:37:32.468] Timer tick, asking Buildbucket for the build status
[17:37:32.697] Build 8888187450697848816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:37:32.697] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:4:0) after 1m0s
[17:38:32.863] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:4:0)
[17:38:32.863] Timer tick, asking Buildbucket for the build status
[17:38:33.091] Build 8888187450697848816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:38:33.091] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:5:0) after 1m0s
[17:39:33.168] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:5:0)
[17:39:33.168] Timer tick, asking Buildbucket for the build status
[17:39:33.395] Build 8888187450697848816: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:39:33.395] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:6:0) after 1m0s
[17:40:16.234] Received PubSub notification, asking Buildbucket for the build status
[17:40:16.407] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:34.121] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:6:0)
[17:40:34.121] Timer tick, asking Buildbucket for the build status
[17:40:34.269] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:34.269] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:8:0) after 1m0s
[17:41:34.376] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:8:0)
[17:41:34.376] Timer tick, asking Buildbucket for the build status
[17:41:34.915] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:34.915] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:9:0) after 1m0s
[17:42:34.904] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:9:0)
[17:42:34.904] Timer tick, asking Buildbucket for the build status
[17:42:35.194] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:35.194] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:10:0) after 1m0s
[17:43:35.257] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:10:0)
[17:43:35.257] Timer tick, asking Buildbucket for the build status
[17:43:35.458] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:35.458] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:11:0) after 1m0s
[17:44:35.530] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:11:0)
[17:44:35.530] Timer tick, asking Buildbucket for the build status
[17:44:35.639] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:35.639] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:12:0) after 1m0s
[17:45:35.773] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:12:0)
[17:45:35.773] Timer tick, asking Buildbucket for the build status
[17:45:36.239] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:36.239] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:13:0) after 1m0s
[17:46:36.302] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:13:0)
[17:46:36.302] Timer tick, asking Buildbucket for the build status
[17:46:36.443] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:36.443] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:14:0) after 1m0s
[17:47:36.462] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:14:0)
[17:47:36.462] Timer tick, asking Buildbucket for the build status
[17:47:36.627] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:36.627] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:15:0) after 1m0s
[17:48:36.779] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:15:0)
[17:48:36.779] Timer tick, asking Buildbucket for the build status
[17:48:37.032] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:37.032] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:16:0) after 1m0s
[17:49:37.041] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:16:0)
[17:49:37.041] Timer tick, asking Buildbucket for the build status
[17:49:37.133] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:37.133] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:17:0) after 1m0s
[17:50:37.299] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:17:0)
[17:50:37.299] Timer tick, asking Buildbucket for the build status
[17:50:37.558] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:37.558] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:18:0) after 1m0s
[17:51:37.690] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:18:0)
[17:51:37.690] Timer tick, asking Buildbucket for the build status
[17:51:37.922] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:37.923] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:19:0) after 1m0s
[17:52:37.951] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:19:0)
[17:52:37.951] Timer tick, asking Buildbucket for the build status
[17:52:38.237] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:38.237] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:20:0) after 1m0s
[17:53:38.540] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:20:0)
[17:53:38.540] Timer tick, asking Buildbucket for the build status
[17:53:38.676] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:38.676] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:21:0) after 1m0s
[17:54:38.902] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:21:0)
[17:54:38.902] Timer tick, asking Buildbucket for the build status
[17:54:39.118] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:39.118] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:22:0) after 1m0s
[17:55:39.205] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:22:0)
[17:55:39.205] Timer tick, asking Buildbucket for the build status
[17:55:39.292] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:39.292] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:23:0) after 1m0s
[17:56:39.381] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:23:0)
[17:56:39.381] Timer tick, asking Buildbucket for the build status
[17:56:39.506] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:39.506] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:24:0) after 1m0s
[17:57:39.581] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:24:0)
[17:57:39.581] Timer tick, asking Buildbucket for the build status
[17:57:39.686] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:39.686] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:25:0) after 1m0s
[17:58:39.763] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:25:0)
[17:58:39.763] Timer tick, asking Buildbucket for the build status
[17:58:39.861] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:39.861] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:26:0) after 1m0s
[17:59:40.026] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:26:0)
[17:59:40.026] Timer tick, asking Buildbucket for the build status
[17:59:40.118] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:40.118] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:27:0) after 1m0s
[18:00:40.294] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:27:0)
[18:00:40.294] Timer tick, asking Buildbucket for the build status
[18:00:40.566] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:40.566] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:28:0) after 1m0s
[18:01:40.641] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:28:0)
[18:01:40.641] Timer tick, asking Buildbucket for the build status
[18:01:41.030] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:41.030] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:29:0) after 1m0s
[18:02:41.221] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:29:0)
[18:02:41.221] Timer tick, asking Buildbucket for the build status
[18:02:41.377] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:41.377] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:30:0) after 1m0s
[18:03:41.428] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:30:0)
[18:03:41.428] Timer tick, asking Buildbucket for the build status
[18:03:41.653] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:41.653] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:31:0) after 1m0s
[18:04:41.692] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:31:0)
[18:04:41.692] Timer tick, asking Buildbucket for the build status
[18:04:41.852] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:41.852] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:32:0) after 1m0s
[18:05:41.911] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:32:0)
[18:05:41.911] Timer tick, asking Buildbucket for the build status
[18:05:42.186] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:42.186] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:33:0) after 1m0s
[18:06:42.395] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:33:0)
[18:06:42.395] Timer tick, asking Buildbucket for the build status
[18:06:42.512] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:42.512] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:34:0) after 1m0s
[18:07:42.558] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:34:0)
[18:07:42.558] Timer tick, asking Buildbucket for the build status
[18:07:42.905] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:42.905] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:35:0) after 1m0s
[18:08:42.949] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:35:0)
[18:08:42.949] Timer tick, asking Buildbucket for the build status
[18:08:43.361] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:43.361] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:36:0) after 1m0s
[18:09:43.482] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:36:0)
[18:09:43.482] Timer tick, asking Buildbucket for the build status
[18:09:43.762] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:43.762] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:37:0) after 1m0s
[18:10:43.858] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:37:0)
[18:10:43.858] Timer tick, asking Buildbucket for the build status
[18:10:43.990] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:43.990] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:38:0) after 1m0s
[18:11:44.003] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:38:0)
[18:11:44.003] Timer tick, asking Buildbucket for the build status
[18:11:44.303] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:44.303] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:39:0) after 1m0s
[18:12:44.341] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:39:0)
[18:12:44.341] Timer tick, asking Buildbucket for the build status
[18:12:44.557] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:44.557] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:40:0) after 1m0s
[18:13:44.596] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:40:0)
[18:13:44.596] Timer tick, asking Buildbucket for the build status
[18:13:44.817] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:44.817] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:41:0) after 1m0s
[18:14:44.904] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:41:0)
[18:14:44.904] Timer tick, asking Buildbucket for the build status
[18:14:45.049] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:45.049] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:42:0) after 1m0s
[18:15:45.108] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:42:0)
[18:15:45.108] Timer tick, asking Buildbucket for the build status
[18:15:45.382] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:45.382] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:43:0) after 1m0s
[18:16:45.484] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:43:0)
[18:16:45.484] Timer tick, asking Buildbucket for the build status
[18:16:45.655] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:45.655] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:44:0) after 1m0s
[18:17:45.686] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:44:0)
[18:17:45.686] Timer tick, asking Buildbucket for the build status
[18:17:45.794] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:45.794] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:45:0) after 1m0s
[18:18:45.915] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:45:0)
[18:18:45.915] Timer tick, asking Buildbucket for the build status
[18:18:46.183] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:46.183] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:46:0) after 1m0s
[18:19:46.219] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:46:0)
[18:19:46.219] Timer tick, asking Buildbucket for the build status
[18:19:46.453] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:46.453] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:47:0) after 1m0s
[18:20:46.543] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:47:0)
[18:20:46.543] Timer tick, asking Buildbucket for the build status
[18:20:46.732] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:46.732] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:48:0) after 1m0s
[18:21:46.813] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:48:0)
[18:21:46.813] Timer tick, asking Buildbucket for the build status
[18:21:46.944] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:46.944] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:49:0) after 1m0s
[18:22:46.953] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:49:0)
[18:22:46.953] Timer tick, asking Buildbucket for the build status
[18:22:47.066] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:47.066] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:50:0) after 1m0s
[18:23:47.191] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:50:0)
[18:23:47.191] Timer tick, asking Buildbucket for the build status
[18:23:47.332] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:47.333] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:51:0) after 1m0s
[18:24:47.512] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:51:0)
[18:24:47.512] Timer tick, asking Buildbucket for the build status
[18:24:47.773] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:47.773] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:52:0) after 1m0s
[18:25:47.837] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:52:0)
[18:25:47.837] Timer tick, asking Buildbucket for the build status
[18:25:48.043] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:48.043] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:53:0) after 1m0s
[18:26:48.142] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:53:0)
[18:26:48.142] Timer tick, asking Buildbucket for the build status
[18:26:48.246] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:48.246] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:54:0) after 1m0s
[18:27:49.902] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:54:0)
[18:27:49.902] Timer tick, asking Buildbucket for the build status
[18:27:50.012] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:50.012] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:55:0) after 1m0s
[18:28:50.235] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:55:0)
[18:28:50.235] Timer tick, asking Buildbucket for the build status
[18:28:50.463] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:50.463] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:56:0) after 1m0s
[18:29:50.497] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:56:0)
[18:29:50.497] Timer tick, asking Buildbucket for the build status
[18:29:52.168] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:52.168] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:57:0) after 1m0s
[18:30:52.251] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:57:0)
[18:30:52.251] Timer tick, asking Buildbucket for the build status
[18:30:52.499] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:52.499] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:58:0) after 1m0s
[18:31:52.585] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:58:0)
[18:31:52.585] Timer tick, asking Buildbucket for the build status
[18:31:52.691] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:52.691] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:59:0) after 1m0s
[18:32:52.785] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:59:0)
[18:32:52.785] Timer tick, asking Buildbucket for the build status
[18:32:53.165] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:53.165] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:60:0) after 1m0s
[18:33:53.172] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:60:0)
[18:33:53.172] Timer tick, asking Buildbucket for the build status
[18:33:53.466] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:53.467] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:61:0) after 1m0s
[18:34:53.544] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:61:0)
[18:34:53.544] Timer tick, asking Buildbucket for the build status
[18:34:53.651] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:53.651] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:62:0) after 1m0s
[18:35:53.859] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:62:0)
[18:35:53.859] Timer tick, asking Buildbucket for the build status
[18:35:54.389] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:54.389] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:63:0) after 1m0s
[18:36:54.523] Handling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:63:0)
[18:36:54.523] Timer tick, asking Buildbucket for the build status
[18:36:54.622] Build 8888187450697848816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:54.622] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Builder (dbg):9053617513767409776:64:0) after 1m0s
[18:37:24.182] Received PubSub notification, asking Buildbucket for the build status
[18:37:24.255] Build 8888187450697848816: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:37:24.255] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1581964643338347",
"created_by": "project:chromium",
"created_ts": "1581960931073125",
"id": "8888187450697848816",
"parameters_json": "{\"builder_name\": \"Linux Builder (dbg)\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4fe2b8c1fe705987af172bf3380227155154f23a\"}}",
"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-48-0ifh\", \"branch\": \"refs/heads/master\", \"buildername\": \"Linux Builder (dbg)\", \"buildnumber\": 168711, \"got_angle_revision\": \"59126651bcf55c506e0de13c2826199d12df8632\", \"got_dawn_revision\": \"215b5370bc4fa9dc5eb0004a0a21b9245296d04b\", \"got_nacl_revision\": \"3651f6c52037c96e034cc422746768d59182bff3\", \"got_revision\": \"4fe2b8c1fe705987af172bf3380227155154f23a\", \"got_revision_cp\": \"refs/heads/master@{#741951}\", \"got_swarming_client_revision\": \"0ac28472d073a515124c85b128c4378a914e0a4d\", \"got_swiftshader_revision\": \"b3d9a2adab8a1b1f12ea518f8f7378bc20511e34\", \"got_v8_revision\": \"63ec1e80ef0dd22442bfe8f76f041afc40b60af4\", \"got_v8_revision_cp\": \"refs/heads/8.2.91@{#1}\", \"got_webrtc_revision\": \"80a82f1527156730c5c4deaea1e423093bb562e1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#30529}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.linux\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4fe2b8c1fe705987af172bf3380227155154f23a\", \"swarm_hashes_refs/heads/master(at){#741951}_without_patch\": {\"accessibility_unittests\": \"f023d67fcc544e080212b623ab6c2138124e8939\", \"angle_unittests\": \"a09f933d72e60fc3994379d8de24226424b6c80d\", \"app_shell_unittests\": \"5d4390029460781bf20c33c4963f0cc6f2ad4707\", \"aura_unittests\": \"72640b48b62cd81b4a15c6c3a186d166f13967e2\", \"base_unittests\": \"377391d80122b00aa08d03453986430e03118e52\", \"base_util_unittests\": \"2faa588b9d7e05556da657087aac24d06beb57c9\", \"blink_common_unittests\": \"7a22834744f3b966d57a17fc5d69f442e5a5dbcc\", \"blink_fuzzer_unittests\": \"4da29dda4830b4363461554e773ebcb17460e51c\", \"blink_heap_unittests\": \"1bf3cbfb8b52050d7e52858603de16d97360b716\", \"blink_platform_unittests\": \"41d5180b3f57d138c6982db98b236049cdacfacf\", \"blink_python_tests\": \"0312e87688a0dd57a1e13e6068b9b5fa0d73e1b7\", \"blink_unittests\": \"35395b880a19f41741bb93df22466035ac78a9e5\", \"blink_web_tests\": \"0b45f67c327c1024cd5cb7a864802721a66ce40c\", \"boringssl_crypto_tests\": \"dfefbbbfdb90cbe783ccbe73d7d8170acb32135a\", \"boringssl_ssl_tests\": \"ab4a9113da4a22f3b6d9862cf613558ba5ee9255\", \"browser_tests\": \"5c7e875ecb88db415af7e2bddf600ec216121b33\", \"cacheinvalidation_unittests\": \"84b90233196aebfc210e554e3e4162453476aef6\", \"capture_unittests\": \"2155e1a6619626af388bc7378de2100dc6b87c46\", \"cast_unittests\": \"529ab0b8a27c2a0050fb305812693ac27a57dcf9\", \"cc_unittests\": \"d04a7988988b2ca129f844d2ea2b899d2d0b3483\", \"chrome_app_unittests\": \"214d6a0339f017d39ae8f7083d692ef42bad904d\", \"chromedriver_unittests\": \"a479aed96151007d7176ddc3764c393d34c4fab7\", \"components_browsertests\": \"e940718f0de30308c22c8be5fc3e032e3bbb2a82\", \"components_unittests\": \"d8be6eb3100d1192be08900dec3bb1d2b20a3e01\", \"compositor_unittests\": \"b857b3e8945c86be29c3db9b9db871fe9d7fedd9\", \"content_browsertests\": \"81d1bc276833867beb35951a4ab4e513cfecf763\", \"content_shell_crash_test\": \"c45c13ed3d127a739504c0d1cb55d1b5f6ef8664\", \"content_unittests\": \"37ad9e7d3d95c145f37470048c0d36e26c12d47d\", \"crashpad_tests\": \"280f4682c7e332f0b3d4562ee0ec5694072b3af9\", \"cronet_tests\": \"61f35087329392ac74689f6fe78b74f124eba291\", \"cronet_unittests\": \"8b45cb34a1da4be96a01e906aee4a56b7e942502\", \"crypto_unittests\": \"75320312a47d20200d594c1353977c8ae32523f2\", \"dbus_unittests\": \"85738b5dbc235b50b96a6aba4c3207be1322d9e0\", \"device_unittests\": \"83e82bdf9576af6222ec435f59354f0a0d896373\", \"display_unittests\": \"6aa751c7f366aba7bb0bb9bb5a5082ad452f1bdf\", \"events_unittests\": \"b5607daea32a081241c619ed641697d9d2bbc148\", \"extensions_browsertests\": \"25c0ef08413396b9bd91ee6452733bab1d8d36fd\", \"extensions_unittests\": \"b00a5bd90146e60e60c1cdec8e97f49ecc82e951\", \"filesystem_service_unittests\": \"05c0d8e6603893aa28aec1fa9e75cc39eeb7a4e7\", \"flatbuffers_unittests\": \"9eab69acab0f6bd1460710d9ac6e8475de73625c\", \"gcm_unit_tests\": \"c39dd60c3cd8f669d5a02e5b6c47a5a10cfa0691\", \"gfx_unittests\": \"07f2155393fb5398d7f83e455f858ad9ec67eb2a\", \"gin_unittests\": \"366b9564915af0ee558d6cf36c6ff2f5b3e39265\", \"google_apis_unittests\": \"b1227f07ffa0e9876740a45da5af7f4a329c898d\", \"gpu_unittests\": \"8c5119012f4d55702c227f2a1c979a398be26044\", \"grit_python_unittests\": \"314a136826c68f2b20f2d52ddf6f5e567311beb1\", \"gwp_asan_unittests\": \"cbb66482bc07b5295f2fccc444342f2c6ed944ba\", \"headless_browsertests\": \"c2ec26e23861d53764982c6afe02e652e9c02a1e\", \"headless_unittests\": \"4b2a88d2c608363bb5d080f5fac481dc4d6a6abf\", \"interactive_ui_tests\": \"87fad56b2c63897a27eff54912df4cd75fe6284f\", \"ipc_tests\": \"0762363e49c8908ea74230eff76ff5d7a846f29f\", \"jingle_unittests\": \"add0e968ca72695162c7488229f4139211a005d6\", \"latency_unittests\": \"9da5a3b8c9be8c112be6fb2777d697104a8e8c37\", \"libjingle_xmpp_unittests\": \"14b47d11826c263b17c830387398c629a947594f\", \"media_blink_unittests\": \"b86ce42a725c11ec163440e9efcba6890d28335f\", \"media_service_unittests\": \"dae2369e706833bbdbe5a63821f34499a08818cf\", \"media_unittests\": \"37ea5fb9ba32e7173f859c65decd8b15d3364134\", \"message_center_unittests\": \"7adc534bcaff1873f908a168379b2531ae6c2dbe\", \"metrics_python_tests\": \"dbda4ab359626c49271523bd6c0d58138f6b94c7\", \"midi_unittests\": \"33b82149fddbdd069c28fc6ca30f55d148daba50\", \"mojo_core_unittests\": \"72d92bf100a48d454b6169b0c3d7764268b009cb\", \"mojo_unittests\": \"776bcb4599182c2e924d2d853ce10e4757e22b0b\", \"nacl_helper_nonsfi_unittests\": \"779b4e07ee75260950c303dc9eaec15f34b7ece3\", \"nacl_loader_unittests\": \"44393eaa5e3cead6e6d536750a8b9b51ff750b15\", \"native_theme_unittests\": \"b628d811bc4b734d2eea68972b84769a13a22852\", \"net_unittests\": \"0adb13917fc6dcc4f4a1a301ea9b5a3e563a28ca\", \"pdf_unittests\": \"03cdab6636ae6ee6c056a6344cd36ed494a4b225\", \"perfetto_unittests\": \"a8ea58eac98be5548e84154d30a89dae95725f79\", \"ppapi_unittests\": \"660c0d41d3f7b3e77bdcf57fd5e6d390ffa2ecc3\", \"printing_unittests\": \"ec213e4c995282a5e323545afc3a49574e53ad61\", \"remoting_unittests\": \"c53e97c3ba98cbf9429d52b8e6016b6f93c20b69\", \"sandbox_linux_unittests\": \"7a145001a6e9392575901aced279514e4280e713\", \"service_manager_unittests\": \"7de206d85a73f447e0153a0943e4bd57ba82ed0c\", \"services_unittests\": \"e9a1c7e7b6168e97af2890939b0f524eba4ed9c9\", \"shell_dialogs_unittests\": \"70c218c7a95006a9f6092fad0d5181375ae762b3\", \"skia_unittests\": \"d6977f27c26978a1b512fd67b8a9608a0cd60231\", \"snapshot_unittests\": \"c4f99db8938c788e0829691a98acaa18a924d28d\", \"sql_unittests\": \"65e5c749a39e73c59ecfdbaee85e9488957efc64\", \"storage_unittests\": \"f13c6e5323ae2e38dd0eb1a8b502756acf6baedc\", \"sync_integration_tests\": \"9b7340d0492660bd0317f98ded85e8b17ca5fe86\", \"telemetry_gpu_unittests\": \"9c012333ffff64fa1db35a1a55d75c5bcb4f3d24\", \"telemetry_perf_unittests\": \"403003d4cd16f1d2122a96eaae974ed984ca8610\", \"telemetry_unittests\": \"a52ceefebd3c162ff27cfcb98e3f75e9bdc1391e\", \"traffic_annotation_auditor_unittests\": \"46848b634ad6aeac8bf4b05c10b69f3cfa033338\", \"ui_base_unittests\": \"0bfcb8dddc22ff9653baa598e807e99b27e51741\", \"ui_touch_selection_unittests\": \"ad168f2b50d90adfcade6a20aea384ca8bbce205\", \"unit_tests\": \"11f8276ce71f16b628f76161ba799d6869d784f0\", \"url_unittests\": \"e6097f90d0044f383a90b7175070cba808310d5a\", \"views_perftests\": \"e2229fe2cdfc5fefc85536e697f03b088b1f010c\", \"views_unittests\": \"bd323ebd82992f241b023287bded34e17d68b4b9\", \"viz_unittests\": \"69eb0a89eaf8a286fe5e9a09ca371d8ba5ff9661\", \"vr_common_unittests\": \"a6f003a10f707dee030af4003bbeb13e165ed4c8\", \"vr_pixeltests\": \"d74ab3ce5e4401186551260e932a6f34e02d600c\", \"webdriver_wpt_tests\": \"c4a301a9e00dc2ff05885db2fc7b87a3522d7576\", \"weblayer_browsertests\": \"1bac07e5822e3bdf7646e956e93e4dcc3c7b4e0c\", \"wm_unittests\": \"28fa84e490bf18a62f43ad9b1a495d379143538d\", \"wtf_unittests\": \"cd709397e80c8c75c569bbad541964c7b57e7e7e\", \"xr_browser_tests\": \"68a790fca179790e354285d5bff6dd06b3a3e818\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_0095fe006eafdabf54002a791fb8c836a288874203ece8347c24b74b8d090ce3_v2\", \"builder_16895d03945fefa0a0694b70850ae78b1bb8ca61113b6e89bc39b6edfabe662c_v2\", \"builder_3e75b41c9bcf12a22209c03b338cce1fafa12941d3074ed1e1ed2450e6b71594_v2\", \"builder_9ccb21220795a1d1972030c6b5d9b4569472a28790e1458f6676edb8e4495e8f_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-48-0ifh\"], \"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\": [\"4815-21a95a1\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1581961215313752",
"status": "COMPLETED",
"status_changed_ts": "1581964643587178",
"tags": [
"build_address:luci.chromium.ci/Linux Builder (dbg)/168711",
"builder:Linux Builder (dbg)",
"buildset:commit/git/4fe2b8c1fe705987af172bf3380227155154f23a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4fe2b8c1fe705987af172bf3380227155154f23a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9053617513767409776",
"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/8888187450697848816/+/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:4a6d09b2ffab4010",
"user_agent:luci-scheduler"
],
"updated_ts": "1581964643590954",
"url": "https://ci.chromium.org/b/8888187450697848816",
"utcnow_ts": "1581964644244636"
}
}
[18:37:24.255] Invocation finished in 1h1m55.492555064s with status SUCCEEDED